builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-050 starttime: 1463444856.31 results: success (0) buildid: 20160516143016 builduid: de00ff9e295c493daa2796e9ea061e37 revision: ae689711c21875e2f732c97264e35527c7f6b963 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.305955) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.306631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.307081) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.328263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.328914) ========= 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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 17:27:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-16 17:27:36 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.496567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.497117) ========= 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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016252 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.549969) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:36.550612) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ae689711c21875e2f732c97264e35527c7f6b963 --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 ae689711c21875e2f732c97264e35527c7f6b963 --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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 17:27:36,744 truncating revision to first 12 chars 2016-05-16 17:27:36,744 Setting DEBUG logging. 2016-05-16 17:27:36,744 attempt 1/10 2016-05-16 17:27:36,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ae689711c218?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 17:27:37,044 unpacking tar archive at: fx-team-ae689711c218/testing/mozharness/ program finished with exit code 0 elapsedTime=0.633690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:37.201007) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:37.201602) ========= script_repo_revision: ae689711c21875e2f732c97264e35527c7f6b963 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:37.202504) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:37.202999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 17:27:37.213694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-16 17:27:37.214289) ========= /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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:27:37 INFO - MultiFileLogger online at 20160516 17:27:37 in /builds/slave/test 17:27:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:27:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:27:37 INFO - {'append_to_log': False, 17:27:37 INFO - 'base_work_dir': '/builds/slave/test', 17:27:37 INFO - 'blob_upload_branch': 'Fx-Team', 17:27:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:27:37 INFO - 'branch': 'Fx-Team', 17:27:37 INFO - 'buildbot_json_path': 'buildprops.json', 17:27:37 INFO - 'config_files': ('talos/linux_config.py',), 17:27:37 INFO - 'default_actions': ('clobber', 17:27:37 INFO - 'read-buildbot-config', 17:27:37 INFO - 'download-and-extract', 17:27:37 INFO - 'populate-webroot', 17:27:37 INFO - 'create-virtualenv', 17:27:37 INFO - 'install', 17:27:37 INFO - 'run-tests'), 17:27:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:27:37 INFO - 'download_minidump_stackwalk': True, 17:27:37 INFO - 'download_symbols': 'ondemand', 17:27:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:27:37 INFO - 'tooltool.py': '/tools/tooltool.py', 17:27:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:27:37 INFO - '/tools/misc-python/virtualenv.py')}, 17:27:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:27:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:27:37 INFO - 'installer_path': 'installer.exe', 17:27:37 INFO - 'log_level': 'info', 17:27:37 INFO - 'log_name': 'talos', 17:27:37 INFO - 'log_to_console': True, 17:27:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:27:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:27:37 INFO - 'opt_config_files': (), 17:27:37 INFO - 'pip_index': False, 17:27:37 INFO - 'sps_profile': False, 17:27:37 INFO - 'sps_profile_interval': 0, 17:27:37 INFO - 'suite': 'dromaeojs', 17:27:37 INFO - 'system_bits': '32', 17:27:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:27:37 INFO - 'title': 'talos-linux64-ix-050', 17:27:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:27:37 INFO - 'use_talos_json': True, 17:27:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:27:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:27:37 INFO - 'work_dir': 'build'} 17:27:37 INFO - ##### 17:27:37 INFO - ##### Running clobber step. 17:27:37 INFO - ##### 17:27:37 INFO - Running pre-action listener: _resource_record_pre_action 17:27:37 INFO - Running main action method: clobber 17:27:37 INFO - rmtree: /builds/slave/test/build 17:27:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:27:37 INFO - Running post-action listener: _resource_record_post_action 17:27:37 INFO - ##### 17:27:37 INFO - ##### Finished clobber step (success) 17:27:37 INFO - ##### 17:27:37 INFO - ##### 17:27:37 INFO - ##### Running read-buildbot-config step. 17:27:37 INFO - ##### 17:27:37 INFO - Running pre-action listener: _resource_record_pre_action 17:27:37 INFO - Running main action method: read_buildbot_config 17:27:37 INFO - Using buildbot properties: 17:27:37 INFO - { 17:27:37 INFO - "project": "", 17:27:37 INFO - "product": "firefox", 17:27:37 INFO - "script_repo_revision": "production", 17:27:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:27:37 INFO - "repository": "", 17:27:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:27:37 INFO - "buildid": "20160516143016", 17:27:37 INFO - "pgo_build": "True", 17:27:37 INFO - "basedir": "/builds/slave/test", 17:27:37 INFO - "buildnumber": 95, 17:27:37 INFO - "slavename": "talos-linux64-ix-050", 17:27:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:27:37 INFO - "platform": "ubuntu64_hw", 17:27:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:27:37 INFO - "branch": "fx-team", 17:27:37 INFO - "repo_path": "integration/fx-team", 17:27:37 INFO - "revision": "ae689711c21875e2f732c97264e35527c7f6b963", 17:27:37 INFO - "stage_platform": "linux64-pgo", 17:27:37 INFO - "builduid": "de00ff9e295c493daa2796e9ea061e37", 17:27:37 INFO - "slavebuilddir": "test" 17:27:37 INFO - } 17:27:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:27:37 INFO - Running post-action listener: _resource_record_post_action 17:27:37 INFO - ##### 17:27:37 INFO - ##### Finished read-buildbot-config step (success) 17:27:37 INFO - ##### 17:27:37 INFO - ##### 17:27:37 INFO - ##### Running download-and-extract step. 17:27:37 INFO - ##### 17:27:37 INFO - Running pre-action listener: _resource_record_pre_action 17:27:37 INFO - Running main action method: download_and_extract 17:27:37 INFO - mkdir: /builds/slave/test/build/tests 17:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:37 INFO - https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:27:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:27:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:27:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:27:38 INFO - Downloaded 1361 bytes. 17:27:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:27:38 INFO - Using the following test package requirements: 17:27:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:27:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:27:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:27:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'jsshell-linux-x86_64.zip'], 17:27:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:27:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:27:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:27:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:27:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:27:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:27:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:27:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:38 INFO - https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:27:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:27:40 INFO - Downloaded 24023864 bytes. 17:27:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:27:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:27:41 INFO - Return code: 0 17:27:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:41 INFO - https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:27:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:27:42 INFO - Downloaded 24023864 bytes. 17:27:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:27:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:27:43 INFO - Return code: 0 17:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:43 INFO - https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:27:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:27:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:27:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:27:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:27:44 INFO - Downloaded 10840194 bytes. 17:27:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:27:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:27:45 INFO - Return code: 0 17:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:45 INFO - https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:27:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:27:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:27:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:27:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:27:46 INFO - Downloaded 58412024 bytes. 17:27:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:27:46 INFO - mkdir: /builds/slave/test/properties 17:27:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:27:46 INFO - Writing to file /builds/slave/test/properties/build_url 17:27:46 INFO - Contents: 17:27:46 INFO - build_url:https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:27:47 INFO - Running post-action listener: _resource_record_post_action 17:27:47 INFO - Running post-action listener: set_extra_try_arguments 17:27:47 INFO - ##### 17:27:47 INFO - ##### Finished download-and-extract step (success) 17:27:47 INFO - ##### 17:27:47 INFO - ##### 17:27:47 INFO - ##### Running populate-webroot step. 17:27:47 INFO - ##### 17:27:47 INFO - Running pre-action listener: _resource_record_pre_action 17:27:47 INFO - Running main action method: populate_webroot 17:27:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:27:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:27:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:27:47 INFO - '--tppagecycles', 17:27:47 INFO - '7'], 17:27:47 INFO - 'tests': ['tsvgm']}}, 17:27:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:27:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:27:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:27:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:27:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['damp', 'tps']}, 17:27:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'tests': ['damp', 'tps']}, 17:27:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['dromaeo_dom']}, 17:27:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:27:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'other-e10s': {'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['a11yr', 17:27:47 INFO - 'ts_paint', 17:27:47 INFO - 'tpaint', 17:27:47 INFO - 'sessionrestore', 17:27:47 INFO - 'sessionrestore_no_auto_restore', 17:27:47 INFO - 'tabpaint']}, 17:27:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['tsvgx', 17:27:47 INFO - 'tsvgr_opacity', 17:27:47 INFO - 'tart', 17:27:47 INFO - 'tscrollx', 17:27:47 INFO - 'cart']}, 17:27:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:27:47 INFO - 'tsvgr_opacity', 17:27:47 INFO - 'tart', 17:27:47 INFO - 'tscrollx', 17:27:47 INFO - 'cart']}, 17:27:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'talos_options': ['--disable-e10s'], 17:27:47 INFO - 'tests': ['tp5o']}, 17:27:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'tests': ['tp5o']}, 17:27:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'talos_options': ['--disable-e10s', 17:27:47 INFO - '--xperf_path', 17:27:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:47 INFO - 'tests': ['tp5n']}, 17:27:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:47 INFO - 'talos_options': ['--xperf_path', 17:27:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:47 INFO - 'tests': ['tp5n']}}, 17:27:47 INFO - 'talos.zip': {'path': '', 17:27:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:27:47 INFO - Running post-action listener: _resource_record_post_action 17:27:47 INFO - ##### 17:27:47 INFO - ##### Finished populate-webroot step (success) 17:27:47 INFO - ##### 17:27:47 INFO - ##### 17:27:47 INFO - ##### Running create-virtualenv step. 17:27:47 INFO - ##### 17:27:47 INFO - Running pre-action listener: _resource_record_pre_action 17:27:47 INFO - Running main action method: create_virtualenv 17:27:47 INFO - Creating virtualenv /builds/slave/test/build/venv 17:27:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:27:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:27:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:27:47 INFO - Using real prefix '/usr' 17:27:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:27:48 INFO - Installing distribute.............................................................................................................................................................................................done. 17:27:49 INFO - Installing pip.................done. 17:27:50 INFO - Return code: 0 17:27:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:27:50 INFO - Using env: {'DISPLAY': ':0', 17:27:50 INFO - 'HOME': '/home/cltbld', 17:27:50 INFO - 'LANG': 'en_US.UTF-8', 17:27:50 INFO - 'LANGUAGE': 'en_US:en', 17:27:50 INFO - 'LOGNAME': 'cltbld', 17:27:50 INFO - 'MAIL': '/var/mail/cltbld', 17:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:50 INFO - 'MOZ_NO_REMOTE': '1', 17:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:50 INFO - 'NO_EM_RESTART': '1', 17:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:50 INFO - 'PWD': '/builds/slave/test', 17:27:50 INFO - 'SHELL': '/bin/bash', 17:27:50 INFO - 'SHLVL': '1', 17:27:50 INFO - 'TERM': 'linux', 17:27:50 INFO - 'TMOUT': '86400', 17:27:50 INFO - 'USER': 'cltbld', 17:27:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:50 INFO - '_': '/tools/buildbot/bin/python'} 17:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:27:50 INFO - Cleaning up... 17:27:50 INFO - Return code: 0 17:27:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:27:50 INFO - Using env: {'DISPLAY': ':0', 17:27:50 INFO - 'HOME': '/home/cltbld', 17:27:50 INFO - 'LANG': 'en_US.UTF-8', 17:27:50 INFO - 'LANGUAGE': 'en_US:en', 17:27:50 INFO - 'LOGNAME': 'cltbld', 17:27:50 INFO - 'MAIL': '/var/mail/cltbld', 17:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:50 INFO - 'MOZ_NO_REMOTE': '1', 17:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:50 INFO - 'NO_EM_RESTART': '1', 17:27:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:50 INFO - 'PWD': '/builds/slave/test', 17:27:50 INFO - 'SHELL': '/bin/bash', 17:27:50 INFO - 'SHLVL': '1', 17:27:50 INFO - 'TERM': 'linux', 17:27:50 INFO - 'TMOUT': '86400', 17:27:50 INFO - 'USER': 'cltbld', 17:27:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:50 INFO - '_': '/tools/buildbot/bin/python'} 17:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:50 INFO - Downloading/unpacking psutil>=3.1.1 17:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:52 INFO - Installing collected packages: psutil 17:27:52 INFO - Running setup.py install for psutil 17:27:52 INFO - building 'psutil._psutil_linux' extension 17:27:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:27:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:27:52 INFO - building 'psutil._psutil_posix' extension 17:27:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:27:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:52 INFO - Successfully installed psutil 17:27:52 INFO - Cleaning up... 17:27:53 INFO - Return code: 0 17:27:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:53 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:27:53 INFO - Using env: {'DISPLAY': ':0', 17:27:53 INFO - 'HOME': '/home/cltbld', 17:27:53 INFO - 'LANG': 'en_US.UTF-8', 17:27:53 INFO - 'LANGUAGE': 'en_US:en', 17:27:53 INFO - 'LOGNAME': 'cltbld', 17:27:53 INFO - 'MAIL': '/var/mail/cltbld', 17:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:53 INFO - 'MOZ_NO_REMOTE': '1', 17:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:53 INFO - 'NO_EM_RESTART': '1', 17:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:53 INFO - 'PWD': '/builds/slave/test', 17:27:53 INFO - 'SHELL': '/bin/bash', 17:27:53 INFO - 'SHLVL': '1', 17:27:53 INFO - 'TERM': 'linux', 17:27:53 INFO - 'TMOUT': '86400', 17:27:53 INFO - 'USER': 'cltbld', 17:27:53 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:53 INFO - '_': '/tools/buildbot/bin/python'} 17:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:27:55 INFO - Installing collected packages: mozsystemmonitor 17:27:55 INFO - Running setup.py install for mozsystemmonitor 17:27:55 INFO - Successfully installed mozsystemmonitor 17:27:55 INFO - Cleaning up... 17:27:55 INFO - Return code: 0 17:27:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:55 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:27:55 INFO - Using env: {'DISPLAY': ':0', 17:27:55 INFO - 'HOME': '/home/cltbld', 17:27:55 INFO - 'LANG': 'en_US.UTF-8', 17:27:55 INFO - 'LANGUAGE': 'en_US:en', 17:27:55 INFO - 'LOGNAME': 'cltbld', 17:27:55 INFO - 'MAIL': '/var/mail/cltbld', 17:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:55 INFO - 'MOZ_NO_REMOTE': '1', 17:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:55 INFO - 'NO_EM_RESTART': '1', 17:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:55 INFO - 'PWD': '/builds/slave/test', 17:27:55 INFO - 'SHELL': '/bin/bash', 17:27:55 INFO - 'SHLVL': '1', 17:27:55 INFO - 'TERM': 'linux', 17:27:55 INFO - 'TMOUT': '86400', 17:27:55 INFO - 'USER': 'cltbld', 17:27:55 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:55 INFO - '_': '/tools/buildbot/bin/python'} 17:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:55 INFO - Downloading/unpacking blobuploader==1.2.4 17:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:57 INFO - Downloading blobuploader-1.2.4.tar.gz 17:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:27:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:27:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:58 INFO - Downloading docopt-0.6.1.tar.gz 17:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:27:58 INFO - Installing collected packages: blobuploader, requests, docopt 17:27:58 INFO - Running setup.py install for blobuploader 17:27:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:27:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:27:58 INFO - Running setup.py install for requests 17:27:58 INFO - Running setup.py install for docopt 17:27:58 INFO - Successfully installed blobuploader requests docopt 17:27:58 INFO - Cleaning up... 17:27:58 INFO - Return code: 0 17:27:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:27:58 INFO - Using env: {'DISPLAY': ':0', 17:27:58 INFO - 'HOME': '/home/cltbld', 17:27:58 INFO - 'LANG': 'en_US.UTF-8', 17:27:58 INFO - 'LANGUAGE': 'en_US:en', 17:27:58 INFO - 'LOGNAME': 'cltbld', 17:27:58 INFO - 'MAIL': '/var/mail/cltbld', 17:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:58 INFO - 'MOZ_NO_REMOTE': '1', 17:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:58 INFO - 'NO_EM_RESTART': '1', 17:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:58 INFO - 'PWD': '/builds/slave/test', 17:27:58 INFO - 'SHELL': '/bin/bash', 17:27:58 INFO - 'SHLVL': '1', 17:27:58 INFO - 'TERM': 'linux', 17:27:58 INFO - 'TMOUT': '86400', 17:27:58 INFO - 'USER': 'cltbld', 17:27:58 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:58 INFO - '_': '/tools/buildbot/bin/python'} 17:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:27:59 INFO - Running setup.py (path:/tmp/pip-pqxGG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:27:59 INFO - Running setup.py (path:/tmp/pip-YXMoS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:27:59 INFO - Running setup.py (path:/tmp/pip-mysF5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:27:59 INFO - Running setup.py (path:/tmp/pip-IxBVfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:27:59 INFO - Running setup.py (path:/tmp/pip-llODTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:27:59 INFO - Running setup.py (path:/tmp/pip-CWsLWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:27:59 INFO - Running setup.py (path:/tmp/pip-Q84xO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:27:59 INFO - Running setup.py (path:/tmp/pip-xxguXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:27:59 INFO - Running setup.py (path:/tmp/pip-FCXH2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:27:59 INFO - Running setup.py (path:/tmp/pip-gA8rbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:27:59 INFO - Running setup.py (path:/tmp/pip-jAMtVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:27:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:27:59 INFO - Running setup.py (path:/tmp/pip-FNCah3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:00 INFO - Running setup.py (path:/tmp/pip-2G886r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:00 INFO - Running setup.py (path:/tmp/pip-AtWqSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:00 INFO - Running setup.py (path:/tmp/pip-By3sQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:00 INFO - Running setup.py (path:/tmp/pip-1DPaB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:00 INFO - Running setup.py (path:/tmp/pip-LoKIi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:28:00 INFO - Running setup.py install for manifestparser 17:28:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:28:00 INFO - Running setup.py install for mozcrash 17:28:00 INFO - Running setup.py install for mozdebug 17:28:00 INFO - Running setup.py install for mozdevice 17:28:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:28:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:28:00 INFO - Running setup.py install for mozfile 17:28:00 INFO - Running setup.py install for mozhttpd 17:28:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:28:00 INFO - Running setup.py install for mozinfo 17:28:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for mozInstall 17:28:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for mozleak 17:28:01 INFO - Running setup.py install for mozlog 17:28:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for moznetwork 17:28:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for mozprocess 17:28:01 INFO - Running setup.py install for mozprofile 17:28:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for mozrunner 17:28:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:28:01 INFO - Running setup.py install for mozscreenshot 17:28:01 INFO - Running setup.py install for moztest 17:28:02 INFO - Running setup.py install for mozversion 17:28:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:28:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:28:02 INFO - Cleaning up... 17:28:02 INFO - Return code: 0 17:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:02 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:28:02 INFO - Using env: {'DISPLAY': ':0', 17:28:02 INFO - 'HOME': '/home/cltbld', 17:28:02 INFO - 'LANG': 'en_US.UTF-8', 17:28:02 INFO - 'LANGUAGE': 'en_US:en', 17:28:02 INFO - 'LOGNAME': 'cltbld', 17:28:02 INFO - 'MAIL': '/var/mail/cltbld', 17:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:02 INFO - 'MOZ_NO_REMOTE': '1', 17:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:02 INFO - 'NO_EM_RESTART': '1', 17:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:02 INFO - 'PWD': '/builds/slave/test', 17:28:02 INFO - 'SHELL': '/bin/bash', 17:28:02 INFO - 'SHLVL': '1', 17:28:02 INFO - 'TERM': 'linux', 17:28:02 INFO - 'TMOUT': '86400', 17:28:02 INFO - 'USER': 'cltbld', 17:28:02 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:02 INFO - '_': '/tools/buildbot/bin/python'} 17:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:28:02 INFO - Running setup.py (path:/tmp/pip-dRR3tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:28:02 INFO - Running setup.py (path:/tmp/pip-jKcRs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:28:02 INFO - Running setup.py (path:/tmp/pip-3WxfXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:28:02 INFO - Running setup.py (path:/tmp/pip-c295mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:28:02 INFO - Running setup.py (path:/tmp/pip-p8Tsuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:28:02 INFO - Running setup.py (path:/tmp/pip-jfiwVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:28:02 INFO - Running setup.py (path:/tmp/pip-ZSBhHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:28:02 INFO - Running setup.py (path:/tmp/pip-MaIapw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:28:02 INFO - Running setup.py (path:/tmp/pip-dK7VAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:28:03 INFO - Running setup.py (path:/tmp/pip-dl9HLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:28:03 INFO - Running setup.py (path:/tmp/pip-xSrgD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:28:03 INFO - Running setup.py (path:/tmp/pip-jEAgDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:03 INFO - Running setup.py (path:/tmp/pip-uUNXeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:03 INFO - Running setup.py (path:/tmp/pip-bJd1Fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:03 INFO - Running setup.py (path:/tmp/pip-o6g3rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:03 INFO - Running setup.py (path:/tmp/pip-J645Ay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:03 INFO - Running setup.py (path:/tmp/pip-RdtKrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:05 INFO - Downloading blessings-1.6.tar.gz 17:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:28:05 INFO - Installing collected packages: blessings 17:28:05 INFO - Running setup.py install for blessings 17:28:05 INFO - Successfully installed blessings 17:28:05 INFO - Cleaning up... 17:28:05 INFO - Return code: 0 17:28:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:06 INFO - Reading from file tmpfile_stdout 17:28:06 INFO - Current package versions: 17:28:06 INFO - argparse == 1.2.1 17:28:06 INFO - blessings == 1.6 17:28:06 INFO - blobuploader == 1.2.4 17:28:06 INFO - docopt == 0.6.1 17:28:06 INFO - manifestparser == 1.1 17:28:06 INFO - mozInstall == 1.12 17:28:06 INFO - mozcrash == 0.17 17:28:06 INFO - mozdebug == 0.1 17:28:06 INFO - mozdevice == 0.48 17:28:06 INFO - mozfile == 1.2 17:28:06 INFO - mozhttpd == 0.7 17:28:06 INFO - mozinfo == 0.9 17:28:06 INFO - mozleak == 0.1 17:28:06 INFO - mozlog == 3.2 17:28:06 INFO - moznetwork == 0.27 17:28:06 INFO - mozprocess == 0.22 17:28:06 INFO - mozprofile == 0.28 17:28:06 INFO - mozrunner == 6.11 17:28:06 INFO - mozscreenshot == 0.1 17:28:06 INFO - mozsystemmonitor == 0.3 17:28:06 INFO - moztest == 0.7 17:28:06 INFO - mozversion == 1.4 17:28:06 INFO - psutil == 3.1.1 17:28:06 INFO - requests == 1.2.3 17:28:06 INFO - wsgiref == 0.1.2 17:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:06 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:28:06 INFO - Using env: {'DISPLAY': ':0', 17:28:06 INFO - 'HOME': '/home/cltbld', 17:28:06 INFO - 'LANG': 'en_US.UTF-8', 17:28:06 INFO - 'LANGUAGE': 'en_US:en', 17:28:06 INFO - 'LOGNAME': 'cltbld', 17:28:06 INFO - 'MAIL': '/var/mail/cltbld', 17:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:06 INFO - 'MOZ_NO_REMOTE': '1', 17:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:06 INFO - 'NO_EM_RESTART': '1', 17:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:06 INFO - 'PWD': '/builds/slave/test', 17:28:06 INFO - 'SHELL': '/bin/bash', 17:28:06 INFO - 'SHLVL': '1', 17:28:06 INFO - 'TERM': 'linux', 17:28:06 INFO - 'TMOUT': '86400', 17:28:06 INFO - 'USER': 'cltbld', 17:28:06 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:06 INFO - '_': '/tools/buildbot/bin/python'} 17:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:28:06 INFO - Cleaning up... 17:28:06 INFO - Return code: 0 17:28:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17: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')]} 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:06 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:28:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:28:06 INFO - Using env: {'DISPLAY': ':0', 17:28:06 INFO - 'HOME': '/home/cltbld', 17:28:06 INFO - 'LANG': 'en_US.UTF-8', 17:28:06 INFO - 'LANGUAGE': 'en_US:en', 17:28:06 INFO - 'LOGNAME': 'cltbld', 17:28:06 INFO - 'MAIL': '/var/mail/cltbld', 17:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:06 INFO - 'MOZ_NO_REMOTE': '1', 17:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:06 INFO - 'NO_EM_RESTART': '1', 17:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:06 INFO - 'PWD': '/builds/slave/test', 17:28:06 INFO - 'SHELL': '/bin/bash', 17:28:06 INFO - 'SHLVL': '1', 17:28:06 INFO - 'TERM': 'linux', 17:28:06 INFO - 'TMOUT': '86400', 17:28:06 INFO - 'USER': 'cltbld', 17:28:06 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:06 INFO - '_': '/tools/buildbot/bin/python'} 17:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:06 INFO - Downloading/unpacking jsonschema 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:28:08 INFO - Downloading/unpacking functools32 (from jsonschema) 17:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:28:08 INFO - warning: no files found matching '*.txt' 17:28:08 INFO - no previously-included directories found matching 'build' 17:28:08 INFO - no previously-included directories found matching 'dist' 17:28:08 INFO - no previously-included directories found matching '.git*' 17:28:08 INFO - Installing collected packages: jsonschema, functools32 17:28:08 INFO - Running setup.py install for functools32 17:28:08 INFO - warning: no files found matching '*.txt' 17:28:08 INFO - no previously-included directories found matching 'build' 17:28:08 INFO - no previously-included directories found matching 'dist' 17:28:08 INFO - no previously-included directories found matching '.git*' 17:28:08 INFO - Successfully installed jsonschema functools32 17:28:08 INFO - Cleaning up... 17:28:08 INFO - Return code: 0 17:28:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:08 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 0x7ffbb1745f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1818770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, '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': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:28:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:28:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:28:08 INFO - Using env: {'DISPLAY': ':0', 17:28:08 INFO - 'HOME': '/home/cltbld', 17:28:08 INFO - 'LANG': 'en_US.UTF-8', 17:28:08 INFO - 'LANGUAGE': 'en_US:en', 17:28:08 INFO - 'LOGNAME': 'cltbld', 17:28:08 INFO - 'MAIL': '/var/mail/cltbld', 17:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:08 INFO - 'MOZ_NO_REMOTE': '1', 17:28:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:08 INFO - 'NO_EM_RESTART': '1', 17:28:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:08 INFO - 'PWD': '/builds/slave/test', 17:28:08 INFO - 'SHELL': '/bin/bash', 17:28:08 INFO - 'SHLVL': '1', 17:28:08 INFO - 'TERM': 'linux', 17:28:08 INFO - 'TMOUT': '86400', 17:28:08 INFO - 'USER': 'cltbld', 17:28:08 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:08 INFO - '_': '/tools/buildbot/bin/python'} 17:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:09 INFO - Downloading/unpacking flake8 17:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:28:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17: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 17: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 17: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 17: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 17: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 17: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 17:28:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:28:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17: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 17: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 17: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 17: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 17: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 17: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 17:28:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17: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 17: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 17: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 17: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 17: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 17: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 17:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:28:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:28:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:28:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:28:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:28:11 INFO - no previously-included directories found matching 'docs/_build' 17:28:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:28:11 INFO - Running setup.py install for pep8 17:28:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:28:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:28:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:28:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:28:11 INFO - no previously-included directories found matching 'docs/_build' 17:28:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:28:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:28:11 INFO - Cleaning up... 17:28:12 INFO - Return code: 0 17:28:12 INFO - Running post-action listener: _resource_record_post_action 17:28:12 INFO - Running post-action listener: _start_resource_monitoring 17:28:12 INFO - Starting resource monitoring. 17:28:12 INFO - ##### 17:28:12 INFO - ##### Finished create-virtualenv step (success) 17:28:12 INFO - ##### 17:28:12 INFO - ##### 17:28:12 INFO - ##### Running install step. 17:28:12 INFO - ##### 17:28:12 INFO - Running pre-action listener: _resource_record_pre_action 17:28:12 INFO - Running main action method: install 17:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:12 INFO - Reading from file tmpfile_stdout 17:28:12 INFO - Detecting whether we're running mozinstall >=1.0... 17:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:28:12 INFO - Reading from file tmpfile_stdout 17:28:12 INFO - Output received: 17:28:12 INFO - Usage: mozinstall [options] installer 17:28:12 INFO - Options: 17:28:12 INFO - -h, --help show this help message and exit 17:28:12 INFO - -d DEST, --destination=DEST 17:28:12 INFO - Directory to install application into. [default: 17:28:12 INFO - "/builds/slave/test"] 17:28:12 INFO - --app=APP Application being installed. [default: firefox] 17:28:12 INFO - mkdir: /builds/slave/test/build/application 17:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:28:28 INFO - Reading from file tmpfile_stdout 17:28:28 INFO - Output received: 17:28:28 INFO - /builds/slave/test/build/application/firefox/firefox 17:28:28 INFO - Running post-action listener: _resource_record_post_action 17:28:28 INFO - ##### 17:28:28 INFO - ##### Finished install step (success) 17:28:28 INFO - ##### 17:28:28 INFO - ##### 17:28:28 INFO - ##### Running run-tests step. 17:28:28 INFO - ##### 17:28:28 INFO - Running pre-action listener: _resource_record_pre_action 17:28:28 INFO - Running main action method: run_tests 17:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:28:28 INFO - Python 2.7.3 17:28:28 INFO - Return code: 0 17:28:28 INFO - grabbing minidump binary from tooltool 17:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:28 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 0x18a79c0>, '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 0x18a88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:28:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:28:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:28:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:28:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:28:28 INFO - Return code: 0 17:28:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:28:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:28:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:28:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:28:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:28:29 INFO - Return code: 0 17:28:29 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/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:28:29 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/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:28:29 INFO - Using env: {'DISPLAY': ':0', 17:28:29 INFO - 'HOME': '/home/cltbld', 17:28:29 INFO - 'LANG': 'en_US.UTF-8', 17:28:29 INFO - 'LANGUAGE': 'en_US:en', 17:28:29 INFO - 'LOGNAME': 'cltbld', 17:28:29 INFO - 'MAIL': '/var/mail/cltbld', 17:28:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:28:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:29 INFO - 'MOZ_NO_REMOTE': '1', 17:28:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:29 INFO - 'NO_EM_RESTART': '1', 17:28:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:29 INFO - 'PWD': '/builds/slave/test', 17:28:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:28:29 INFO - 'SHELL': '/bin/bash', 17:28:29 INFO - 'SHLVL': '1', 17:28:29 INFO - 'TERM': 'linux', 17:28:29 INFO - 'TMOUT': '86400', 17:28:29 INFO - 'USER': 'cltbld', 17:28:29 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728', 17:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:29 INFO - '_': '/tools/buildbot/bin/python'} 17:28:29 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/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:28:30 INFO - mozversion application_buildid: 20160516143016 17:28:30 INFO - mozversion application_changeset: ae689711c21875e2f732c97264e35527c7f6b963 17:28:30 INFO - mozversion application_display_name: Nightly 17:28:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:28:30 INFO - mozversion application_name: Firefox 17:28:30 INFO - mozversion application_remotingname: firefox 17:28:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:28:30 INFO - mozversion application_vendor: Mozilla 17:28:30 INFO - mozversion application_version: 49.0a1 17:28:30 INFO - mozversion platform_buildid: 20160516143016 17:28:30 INFO - mozversion platform_changeset: ae689711c21875e2f732c97264e35527c7f6b963 17:28:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:28:30 INFO - mozversion platform_version: 49.0a1 17:28:30 INFO - using testdate: 1463444910 17:28:30 INFO - actual date: 1463444910 17:28:30 INFO - starting webserver on 'localhost:41878' 17:28:30 INFO - SUITE-START | Running 2 tests 17:28:30 INFO - TEST-START | dromaeo_css 17:28:30 INFO - operating with platform_type : linux_ 17:28:30 INFO - Initialising browser for dromaeo_css test... 17:28:30 INFO - TEST-INFO | started process 25379 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5cJqpc/profile http://localhost:41878/getInfo.html) 17:28:34 INFO - PROCESS | 25379 | __metrics Screen width/height:1600/1200 17:28:34 INFO - PROCESS | 25379 | colorDepth:24 17:28:34 INFO - PROCESS | 25379 | Browser inner width/height: 1024/697 17:28:34 INFO - PROCESS | 25379 | __metrics 17:28:35 ERROR - PROCESS | 25379 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:28:35 INFO - TEST-INFO | 25379: exit 0 17:28:35 INFO - Browser initialized. 17:28:35 INFO - Running cycle 1/1 for dromaeo_css test... 17:28:35 INFO - TEST-INFO | started process 25462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5cJqpc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:47 INFO - PROCESS | 25462 | RSS: Main: 152752128 17:28:47 INFO - PROCESS | 25462 | 17:29:38 INFO - PROCESS | 25462 | 1463444978681 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:31:47 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41878/tests/dromaeo/cssquery-ext.html) 17:31:48 INFO - PROCESS | 25462 | RSS: Main: 231890944 17:31:48 INFO - PROCESS | 25462 | 17:34:50 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-ext.html (next: http://localhost:41878/tests/dromaeo/cssquery-jquery.html) 17:34:51 INFO - PROCESS | 25462 | RSS: Main: 237588480 17:34:51 INFO - PROCESS | 25462 | 17:37:53 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41878/tests/dromaeo/cssquery-mootools.html) 17:37:53 INFO - PROCESS | 25462 | RSS: Main: 241479680 17:37:53 INFO - PROCESS | 25462 | 17:40:55 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41878/tests/dromaeo/cssquery-prototype.html) 17:40:56 INFO - PROCESS | 25462 | RSS: Main: 239218688 17:40:56 INFO - PROCESS | 25462 | 17:43:58 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41878/tests/dromaeo/cssquery-yui.html) 17:43:59 INFO - PROCESS | 25462 | RSS: Main: 228581376 17:43:59 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | Cycle 1(1): loaded http://localhost:41878/tests/dromaeo/cssquery-yui.html (next: http://localhost:41878/tests/dromaeo/cssquery-dojo.html) 17:47:02 INFO - PROCESS | 25462 | RSS: Main: 270725120 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | __start_tp_report 17:47:02 INFO - PROCESS | 25462 | _x_x_mozilla_page_load 17:47:02 INFO - PROCESS | 25462 | _x_x_mozilla_page_load_details 17:47:02 INFO - PROCESS | 25462 | |i|pagename|runs| 17:47:02 INFO - PROCESS | 25462 | |0;dojo.html;3179.205198700325;3186.745060395167;3210.1814037420445;3223.306988997365;3253.8698452061917;5275.894482110355;5345.572354211664;5395.190721391793;5460.453954604538;5516.034693928562;5391.110466772988;5435.42927992561;5448.318960129984;5456.427075157111;5506.256655351526;4906.043321552299;4921.064997650435;4929.186684197121;5021.774020169101;5033.194688849785;4966.726830024347;5059.139946209153;5076.289319495274;5082.79668813247;5091.516305950929;4861.9027619447415;4884.658073934826;4982;4983.95336979233;5034.144195486776;4914.11545921734;4937.580305674022;4942.456329803718;4945.208766597327;4950.925736113961;4842.297866809293;4845.176320025604;4925.605951523869;4926.975365123188;4935.703857768545;5265.078611243017;5295.814646487393;5342.839714808562;5365.275687782138;5393.217983392426;5800.564957628195;5859.033259512175;5864.912026319608;5866.6186697864505;5868.2371291732225;5676.233708449389;5787.594868359217;5857.209276747627;5874.588778785486;5929.940700592982;5429.185622156708;5474.39781624025;5505.752241149158;5525.972370138164;5526.668399896019;5921.73352199152;5950.137230101611;5951.642901425928;5997.310309314397;6006.279246490449;5670.121131224667;5706.9714651426475;5740.799072032459;5777.162311464814;5783.190353350535;5855.092460668603;5860.443257890579;5861.941380586225;5863.67749773758;5910.527157827363;5821.446962538636;5833.125031245348;5834.27071616048;5838.67887266196;5842.678652674059;5681.119426488901;5721.141828725641;5797.652140871562;5843.152742852262;5852.590318677651;4395.868123956287;4444.200043992112;4445.488768791565;4470;4483.618892394118;3922.7646341219047;3928.3125453046173;3950.841966321315;3966.5240171179644;3982.2234664240195;5278.656887302313;5438;5490.1764735289225;5541.0580201365865;5595.720213989365;5849.415058494202;5857.648541087548;5862.179294898718;5881.764729410861;5918.704064796743;5472.26124473199;5485.204645326406;5491.972540137273;5498.340199176125;5507.614466987273;4873.829415970495;4961.652684312064;4972.030454061423;4994.225894986283;5017.197248440232;6001.489873360726;6012.729427175787;6023.879522409527;6024.759009639653;6033.215681961405;5840.2115714378015;5856.853578660568;5868.413158684098;5871.266091738533;5885.028970219866;5824.825255242349;5933.881322373615;5950.047992321208;5978.282606087298;6029.879402412015;5714.599978001666;5751.683657398883;5759.136129580596;5761.308642962871;5764.3371012332445;5655.7454914528125;5847.093700476433;5849.181114644039;5857.677827719346;5879.794207202727;5687.544996400361;5707.4577915098;5735.053716136791;5750.108733146369;5770.1344798278915;5689.317281926195;5733.770649174153;5736.741846616996;5740.167675687086;5759.452851979056;5450.454954504517;5551.972240138935;5653.491185793298;5654.180143879197;5666.660000399989;5624.7187640616985;5668.829935101953;5707.286589176353;5722.4277572242445;5845.035569131216;5588.273524441796;5623.409541998195;5631.493165615114;5638.210650509014;5639.774409023593 17:47:02 INFO - PROCESS | 25462 | |1;ext.html;622.7322251431884;640.2285246278235;649.5063751548824;653.1019847709399;656.1207981305054;32440;36916.44625330619;37317.253654926884;37471.25057498848;40238.798806006;46466;46535.53464465354;46570.30144547835;46705.299420508716;46791.29813052798;7907;8939.240164586;9078.773030674252;9081.863772043425;9870.753231169207;10391.792164156712;10803.081738052275;10810.189235807307;10816.78366432671;10826.133909287277;10857.728556786065;10893.291936024134;10912.618058367958;10913.454327283644;10916.617918372858;8146.918530814675;8291.461055031403;8317.916820831775;10538.367697938149;10817.783644327108;10416.843747343795;10803.945980270048;10804.0816530595;10810.189235807346;10829.945850270777;42489.36265956013;42915.35626965567;42923.78538107289;42933.78533107346;42946.355804662955;3380.3239352129426;3467.9653203468147;3527.4532447470683;3681.742278040538;3788;31687.366252674816;32075.6792432075;32631.18422039468;33421.33157336864;33839.323213535594;4316.654667626613;4388.5830846069575;4393.476803931777;4405.779711014468;4539.568740969636;358896;359127;359328;359663.9999999974;360989;35432;59563.70218148968;59976;59992;60056.699716501134;34842.82578587091;36546;36612.633873661456;36620.26759464796;36646.450303245474;14553.490627827976;14671.413143474141;14704.852951470562;14706.705865882623;14707.264636768332;11431.657050288504;12097.57658481949;12302.138850280395;12334.444949977273;12590.992720582331;8636.309095272363;8684.43551169172;8693.956530217309;8706.477611343213;8755.693550725695;3314.237725323141;3346.096553930474;3363.5291059251726;3371.9662803371652;3388.9152771180425;1011.7672935224939;1044.9863632276679;1060.2843080920502;1115.4311301236266;1200.9159358844797;33294.33411331808;33499.49750753741;33916.32167356639;33921.83039084838;34430.82784586111;1082.6914329416236;1153.9307641541534;1166.8133098704166;1199.6640940536663;1211.987880121205;18011.549711257063;18051.729224061648;18121.275148994155;18426.447206583824;18429.078546072644;576697;590018;593606;598481;600132;59366.70316648475;60433;60649;60681.39318606846;60695;1263.7409331087106;1286.4854058376536;1291.1349395904767;1340.1155237543176;1374.8006539051783;25592.616110758725;33090.33819323552;33287.334253314315;35640.82179589086;35803.00892342561;56416.717916410154;57074;57108.71445642745;57194.42805571891;57200.42799571951;491697;492016;495709;495872;497000;25443.87278063672;25961.79324757417;25985.610215847155;28721.42557148887;29187.270318241364;48007.75996119997;50480.74759626178;51975.48024519706;53509.46490535045;53572.732136339066;40691;43184;43863.34204986991;43871.34192987044;44302.778486108655;42253;43189.7840510808;43221.351679725456;43420.348694768974;43861.342079869464;42923.57076429196;43202.35196471993;43588.78205608952;43646.781766090964;44190;35412.645873541966;37949;38665;38819.80590097144;39119.41320880246;29205.707942921148;29318.7068129316;29332;29576;29596.704032959395 17:47:02 INFO - PROCESS | 25462 | |2;jquery.html;393.3156308024039;396.84126349460223;397.13226599879283;398.6750798099549;414.63097842919785;18361.265549378026;19791.604167916652;19809.702854457177;19877.105530251138;20517.589648207064;17111.74332385012;17783.28866845328;18023.549411264692;19634;19656;5788.913166302498;5809.477147056764;5916.940830591703;5922.526197904157;5977.073553599199;5756;5844.853878653025;5895.734691938873;5907.291125064999;5913.9112913306335;5756.222909907172;5859.209006784094;5893.233879595668;5929.851753706149;5940.168376427304;5771.826845194651;5780.7687692492245;5835.79574714883;5841.941580584183;5875.676837773921;5128.512791284822;5236.738163091868;5267.710275934822;5271.235670827747;5273.604479664041;22760.658590121162;23109;23144.3056708299;23312.65031024536;23499.882500587388;2179.869207847534;2305.72331320241;2334.2180369576213;2336.123953517431;2377.964330535026;10609.257351985365;10650.680479585624;10774.838377424345;12662.746745065138;14660.9004324675;1981.4749091490767;1991.2632326039459;1991.7750583391255;2076.6158260721813;2109.7847260376543;65399;78685.60657196735;81117;81793.59103204505;82999.5850020746;10165.390076595428;10207.795844083077;10822.729431764268;10976.286541374786;11178.105751539857;14529;15431.228438578026;15469.690606187813;15932.203389830462;15959.601009974844;8087.797805054803;9632.855507167398;9820.361676491006;10015.933081059433;10278.280520363653;8242.835143297101;8407.62165702545;8507.53208573522;8535.658573656985;8561.186687264824;8718.517851965182;8993.595288212045;9277.58250878712;9310;9360.485173315534;3203.583534140593;3323.567936168283;3470.4967779671806;3476.322117187124;3619.9819000904827;884.8407286688329;946.4510583861344;947.8436058050482;995.0795514149382;1060.6870973062964;18515;18671.692981491437;18835.90582047081;19796.208151674058;19997.500062498555;948.6679662118204;970.3304719743354;984.2421335571713;1020.1430798129607;1047.2826114111858;8032.357411407073;10739.570417183382;10988.340699558052;11145.442727863574;11178.552857885594;401080;410618;471719;482058;487103;89591;90491.54754226319;91155.54422227846;93185.53407233056;97008.51495742617;473.51701264710493;505.83307508522097;514.73233918363;514.8429728932637;516.2488579117451;10515.737106572396;10631.627893023708;10940.945295273472;11394.658160255205;11433.714157146138;42005;42456.5754342465;42724.145517088866;43071.35392969171;43365;59008.409915900294;59809.70095149496;59826.401735983825;60001;60405.697971509864;8596.91403085961;8736.694215702673;8885.37802353855;9018.729438116867;9048;10063.69808905734;13204.339783010619;13805;13875.444982200599;13958.651033724238;11481.196316258116;11788.76422471563;11789;11795.52817887275;11986.280823150639;11807.468663909973;11916.106292028306;11972.940135299616;11988.520459181884;12271.263724176579;11691.766164676828;12021.278723276631;12240.877591223974;12277.570285040338;12317.260964342167;8790.208881200722;8930.955345223232;8942.50816205115;8952.731418057469;9018.413803102778;9096;11550.364729939598;11979.76040479203;12067;12239.32683702405 17:47:02 INFO - PROCESS | 25462 | |3;mootools.html;1412.6750847305113;1487.4273404739172;1528.4268399350244;1660.4908757669941;1819.7270409438584;8475.91524084759;9185.678501252458;9187.173154416101;9444.811103777938;9450.66922657707;8765.342599305062;8959.37284390093;8969.416987895782;8973.82052358954;8975.281977441806;2340.32130682102;2375.406148462884;2378.357843382286;2378.595638741418;2378.9999999999914;2349.400902769791;2365.1012615206196;2423.4062654649633;2429.2712186344115;2429.9271021869286;2354.858708477488;2365.6215005599106;2385.129427758864;2390.5457962987066;2401.4836810085826;2306.642470417088;2337.31049340445;2341.426350544119;2351.7530659280787;2364.893579788905;2261.9208327708616;2317.5249073939804;2326.220716060122;2326.430024643956;2330.0563271874917;7823.413244006722;8643.481391116491;9152.359334846564;9191;9203.033681463483;5077.5684066854365;5322.946770532284;5327.360716714019;5352.973235133838;5354.625176237665;3336.5495658086334;3344.6320904700706;3416.214270717724;3456.6370531094376;3458.775179613342;1105.7512059786563;1127.1771606727134;1135.3017893995216;1144.1476100305326;1163.139276935062;13442.193468391928;13519.797203041962;13522.256275904821;13523.188608683511;13532.052756307063;4672.369230153954;4763.809447622057;4764.618830493552;4844.2007068833955;4854.077725232195;11169.218154729091;11194.10447164225;11196.440177991231;11197.832032519518;11204.327740335606;5198.92201616976;5205.06308864408;5205.687658740488;5208.739563021833;5212.452692467312;4775.6657034007285;4858.951410485921;4917;5086.669366491166;5090.618203634668;3985.5416627088257;4035.6569691575955;4061.2283666103344;4067.1458993611673;4141.420201171778;1162.8953394194564;1173.196360493065;1185.4309931233056;1186.258588382261;1220.2922305063041;826.5040975414823;903.2367649336301;905.158202871336;908.822779557993;917.4495302818256;5663.631863928831;5687.51656109227;5783.508401785895;5916.911246331309;5944.256967879015;445.82835608290395;448.12615399969536;449.99100017999456;456.75563573488023;481.63877092180866;3353.5975682918206;3761.055358684997;3902.1415288636454;3940.6847452203756;4028.778417187083;11686.532538698528;11750.353730544732;11871.169018168644;11881.88118118825;11909.702257443632;11241;11594.536218551164;11608.419579021016;11616.070714342832;11624.476898539586;8372.623231954576;8453.619587118594;8476.152384761475;8590.098039705867;8714.738557843275;5893.852653683693;5939.821805345847;5967.194428752063;6021.488173505301;6048.0625503047095;10475.842862356918;10696.358218506915;10793.892061079288;10820.837687434852;10826.296290741077;10772.676819695422;10777.407242601419;10997;11086.833697494383;11209.663710088711;7813.765587032398;7844.137144914169;7895.84208315819;7907.130215676386;7908.8813667793875;8827.24968377695;8909.73270801877;8914.064023277722;8920;9114.817703646022;7851.764447066597;7896.605169741375;7979.521228726295;7986.8801967971685;8221.383396245425;7889.842203156019;7914.572240940575;7954.284114429729;8008.559529225716;8142.715004974797;6961.164660240601;7037.225905150327;7049.471289653194;7050.365467107985;7055.61194134307;4886.682365646222;4908.558229759339;4973.900521989613;4980.676256043345;5019.422766381766;5037.608524268242;5080.796768129381;5086.872828179326;5168.870778230575;5193.298904647824 17:47:02 INFO - PROCESS | 25462 | |4;prototype.html;344.5107946715664;344.8810160494629;353.66755250064955;354.2472246476238;354.2613650538627;5414.918776218355;5449.673019618821;5489.560835133189;5490.588205884555;5498.1752737089455;5381.354237491497;5383.757730902109;5422.8069824638615;5439.3200849893765;5444.50999410053;3886.378179491268;4094.0379010932484;4233.343831706091;4248.893777655552;4261.211675839964;4247.447831781864;4259.723117997336;4260.211860805761;4261.296886013804;4262.46719160105;4075.8981025474454;4222.662187025046;4228.450301460805;4230.936535951963;4241;4115.814788334532;4252.744835309892;4260.297050986584;4270.850520231777;4280.550542193056;3750.955729127632;3759.7368184227116;3767.755095918784;3767.830447629836;3798.4112462568346;5164.2253661950635;5231.686098834082;5263.4473380295285;5274.235235890813;5285.497877701612;4943.678660887031;4948.430930443009;4956.6282528810125;4965;4977.352944117278;5381.973090134564;5517.420670829545;5663.7168141593165;5668.659880407189;5690.772369105231;3995.862213715648;4091.4272001919753;4093.9385909211387;4100.36444351126;4119.711620186588;5864.237649105632;5877.235959325303;5886.793962211302;5900.527957763412;5918.348981612019;4020.718549701493;4185.686073544496;4214.473190851144;4222.4299719538085;4229.281022226229;4250.723702959298;4267.615914567704;4278.187144442546;4288.099499105223;4311.555628864366;4842.18418093215;4890.608751299887;4920.581750551171;4926.679765815211;4934.69230653877;5469.206964990054;5662.886742265132;5693.402192769782;5740.282464691913;5751.4823665869435;1462.1227263641942;1466.8826493880465;1467.0464198271209;1471.4776254429737;1473.042522360453;929.7303781903172;936.7611259128879;948.8624149498285;954.7756277274835;955.5365647660878;4852.684575502581;4920.458749537548;4939.234418665113;4945.876353091202;4948.134076536592;5202.401723801736;5209.921851172236;5223.634345595772;5244.475552444725;5245.4888795635725;4571.337156112345;4886.436340371119;4922.852314430573;4928.162212423897;4930.359053323117;2734.002089237414;2755.9448811024067;2762.9447411051665;2774.8751306190866;2795.68602756704;5310.415854256029;5338.572914166857;5348.946510534923;5360.839174824683;5375.2854808321545;5314.73426328682;5327.893442131136;5508.807191748269;5975.850603734854;6027.457528822427;5801.274840644919;5803.47768700819;6036.434515330967;6058.485028772516;6069.453749162597;5875.148103525051;5884.6469211847425;5900.380460051804;5906.645601263938;5927.318358388668;5642.633228840113;5815.563832712648;5824.184614153937;5829.329627092939;5841.474267315962;6001.159837622824;6010.278766548042;6031.431827724736;6058.455448711464;6070.48400885912;4308.935365969451;4333.674974376871;4349.934750978801;4365.978170109129;4366.847160349373;5648.971755141198;5903.586748927534;5903.852403689943;5917.940820591911;5944.702764861826;5723.6851973141875;5729.54163666914;5769.3942136076785;5775.306963164448;5780.768769249351;5571.8885622288135;5719.456651618089;5735.36910939788;5738.885222295614;5761.5102716269585;5577.470140336662;5608.85977850557;5631.971840140773;5633.690147041952;5661;5743.310802703702;5757.827265182052;5785.9132113017495;5805.883882322245;5815.94184058154;5477.561795056306;5644.266245388238;5681.431856814285;5687.459691329317;5690.971545142248 17:47:02 INFO - PROCESS | 25462 | |5;yui.html;652.451940370089;823.1768231768232;855.6534603485586;856.4090777363618;856.695872965097;13525.256110913895;14319.785203221947;14453.277336133178;14480.493182738606;14525.346359413812;14743.410263589443;14761.335739891703;14775.556733298019;14784.112953222813;14822.036567623098;684.934931181537;821.4906757810166;839.2866063845728;844.2486187293314;846.4878748357247;835.1439774231432;838.7609531283586;841.5539763925129;841.6338892581747;841.7432683031673;885.2165833237567;888.2006194425037;890.7105190812979;891.4383938118976;891.8929728432597;706.6890568149997;862.3144600042981;881.9955900220522;883.8453270677605;887.104024934814;644.0950464597248;644.5423749138071;644.6390021587926;644.8097811145723;645.2192846655553;14430.350634221486;14455.060421072596;14462.855371446256;14467.710645787025;14482.493112741004;13927;13966.650833729136;14158.50445234412;14178.50375236872;14236.715265694627;12037.157398982075;12169.878301216962;12181.147319687625;12265.632031039084;12349.93825030878;4389.429374181368;4447.444069491314;4463.129689710508;4528.547145285478;4532.750698711569;55067;55087.44912550903;55143;55243;55354.72322638361;10397.792044159074;10441.843372349269;10565.577376904992;10632.89367106319;10839.83740243897;19606.117724702424;19762.11070501831;19792.50518737014;20298.18807247723;20336.389908302775;7823.100343460461;8106.2299081588335;8136.186381361935;8424.03123640789;8538.188872057144;4615.723056616614;4705.364775755229;4722.574968252874;4781.665283430196;4881.658283920163;3314.105191598255;3409.9659003410143;3420.025292791592;3422.0760394693793;3423.630547780858;632.3486808587163;642.9453496452854;644.78399736088;644.7839973608893;645.7481582182952;698.664640972326;777.1296148313812;799.2646764976236;809.9757007289791;812.7399232245743;9440.716778496657;9989.900100998897;10020.549075291628;10088.243381746399;10101.595936162472;607.490065109453;714.0075295339482;766.6856588798678;786.1705900275265;787.1970589998169;4762.190427627243;4788.856334309976;4922.9753851231235;4967.428745694219;4982.77577509013;69015;70268.64865675638;70283;70381.64809175921;74949;52086;53340.46659533355;61402.078968815506;62834.685826570574;63901.36098638954;53074.87700492014;53164.73417632964;53646.195307070426;54616;57007.42992570021;9775.969429677323;9817.901820981984;9820.754481138028;9990.436913513975;10033.749156271151;36748.81625591855;37466.81266593649;37918.81040594779;38169.23661526809;39335.74108069252;49321;49662.50337496579;49863.75068124781;49895.75052124716;50194.49805501898;12646.30445325516;12711.80932285998;12830.358482075671;12849.486020559447;13166.802497962346;13780.862191377957;14187.574372768833;14503.92748036253;14662.266886655838;15280.15959122259;13419.127756695783;13548.932255338661;13639.727205456034;14011.229382384066;14306.785398219243;13322.600321990356;13444.865551344361;13537.729245415234;13958.58124256274;14044.368003439666;13293.800592991307;13313.933430332787;13538.390772415069;13851.86148138546;14083.014189006672;10007.699769006942;10127.949360253151;10402.786871317128;10539.473026348805;10627.521761520904;10737.46312684347;11029.117670586173;11047.668569942914;11048;11171.608993685182 17:47:02 INFO - PROCESS | 25462 | __end_tp_report 17:47:02 INFO - PROCESS | 25462 | __start_cc_report 17:47:02 INFO - PROCESS | 25462 | _x_x_mozilla_cycle_collect,873 17:47:02 INFO - PROCESS | 25462 | __end_cc_report 17:47:02 INFO - PROCESS | 25462 | __startTimestamp1463446022369__endTimestamp 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | ------- Summary: start ------- 17:47:02 INFO - PROCESS | 25462 | Number of tests: 6 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#0] dojo.html Cycles:165 Average:5409.97 Median:5639.77 stddev:611.73 (10.8%) stddev-sans-first:588.05 17:47:02 INFO - PROCESS | 25462 | Values: 3179.2 3186.7 3210.2 3223.3 3253.9 5275.9 5345.6 5395.2 5460.5 5516.0 5391.1 5435.4 5448.3 5456.4 5506.3 4906.0 4921.1 4929.2 5021.8 5033.2 4966.7 5059.1 5076.3 5082.8 5091.5 4861.9 4884.7 4982.0 4984.0 5034.1 4914.1 4937.6 4942.5 4945.2 4950.9 4842.3 4845.2 4925.6 4927.0 4935.7 5265.1 5295.8 5342.8 5365.3 5393.2 5800.6 5859.0 5864.9 5866.6 5868.2 5676.2 5787.6 5857.2 5874.6 5929.9 5429.2 5474.4 5505.8 5526.0 5526.7 5921.7 5950.1 5951.6 5997.3 6006.3 5670.1 5707.0 5740.8 5777.2 5783.2 5855.1 5860.4 5861.9 5863.7 5910.5 5821.4 5833.1 5834.3 5838.7 5842.7 5681.1 5721.1 5797.7 5843.2 5852.6 4395.9 4444.2 4445.5 4470.0 4483.6 3922.8 3928.3 3950.8 3966.5 3982.2 5278.7 5438.0 5490.2 5541.1 5595.7 5849.4 5857.6 5862.2 5881.8 5918.7 5472.3 5485.2 5492.0 5498.3 5507.6 4873.8 4961.7 4972.0 4994.2 5017.2 6001.5 6012.7 6023.9 6024.8 6033.2 5840.2 5856.9 5868.4 5871.3 5885.0 5824.8 5933.9 5950.0 5978.3 6029.9 5714.6 5751.7 5759.1 5761.3 5764.3 5655.7 5847.1 5849.2 5857.7 5879.8 5687.5 5707.5 5735.1 5750.1 5770.1 5689.3 5733.8 5736.7 5740.2 5759.5 5450.5 5552.0 5653.5 5654.2 5666.7 5624.7 5668.8 5707.3 5722.4 5845.0 5588.3 5623.4 5631.5 5638.2 5639.8 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#1] ext.html Cycles:180 Average:63213.88 Median:36616.45 stddev:130843.49 (357.3%) stddev-sans-first:131126.15 17:47:02 INFO - PROCESS | 25462 | Values: 622.7 640.2 649.5 653.1 656.1 32440.0 36916.4 37317.3 37471.3 40238.8 46466.0 46535.5 46570.3 46705.3 46791.3 7907.0 8939.2 9078.8 9081.9 9870.8 10391.8 10803.1 10810.2 10816.8 10826.1 10857.7 10893.3 10912.6 10913.5 10916.6 8146.9 8291.5 8317.9 10538.4 10817.8 10416.8 10803.9 10804.1 10810.2 10829.9 42489.4 42915.4 42923.8 42933.8 42946.4 3380.3 3468.0 3527.5 3681.7 3788.0 31687.4 32075.7 32631.2 33421.3 33839.3 4316.7 4388.6 4393.5 4405.8 4539.6 358896.0 359127.0 359328.0 359664.0 360989.0 35432.0 59563.7 59976.0 59992.0 60056.7 34842.8 36546.0 36612.6 36620.3 36646.5 14553.5 14671.4 14704.9 14706.7 14707.3 11431.7 12097.6 12302.1 12334.4 12591.0 8636.3 8684.4 8694.0 8706.5 8755.7 3314.2 3346.1 3363.5 3372.0 3388.9 1011.8 1045.0 1060.3 1115.4 1200.9 33294.3 33499.5 33916.3 33921.8 34430.8 1082.7 1153.9 1166.8 1199.7 1212.0 18011.5 18051.7 18121.3 18426.4 18429.1 576697.0 590018.0 593606.0 598481.0 600132.0 59366.7 60433.0 60649.0 60681.4 60695.0 1263.7 1286.5 1291.1 1340.1 1374.8 25592.6 33090.3 33287.3 35640.8 35803.0 56416.7 57074.0 57108.7 57194.4 57200.4 491697.0 492016.0 495709.0 495872.0 497000.0 25443.9 25961.8 25985.6 28721.4 29187.3 48007.8 50480.7 51975.5 53509.5 53572.7 40691.0 43184.0 43863.3 43871.3 44302.8 42253.0 43189.8 43221.4 43420.3 43861.3 42923.6 43202.4 43588.8 43646.8 44190.0 35412.6 37949.0 38665.0 38819.8 39119.4 29205.7 29318.7 29332.0 29576.0 29596.7 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#2] jquery.html Cycles:180 Average:27990.44 Median:397.90 stddev:74717.44 (18777.8%) stddev-sans-first:74898.31 17:47:02 INFO - PROCESS | 25462 | Values: 393.3 396.8 397.1 398.7 414.6 18361.3 19791.6 19809.7 19877.1 20517.6 17111.7 17783.3 18023.5 19634.0 19656.0 5788.9 5809.5 5916.9 5922.5 5977.1 5756.0 5844.9 5895.7 5907.3 5913.9 5756.2 5859.2 5893.2 5929.9 5940.2 5771.8 5780.8 5835.8 5841.9 5875.7 5128.5 5236.7 5267.7 5271.2 5273.6 22760.7 23109.0 23144.3 23312.7 23499.9 2179.9 2305.7 2334.2 2336.1 2378.0 10609.3 10650.7 10774.8 12662.7 14660.9 1981.5 1991.3 1991.8 2076.6 2109.8 65399.0 78685.6 81117.0 81793.6 82999.6 10165.4 10207.8 10822.7 10976.3 11178.1 14529.0 15431.2 15469.7 15932.2 15959.6 8087.8 9632.9 9820.4 10015.9 10278.3 8242.8 8407.6 8507.5 8535.7 8561.2 8718.5 8993.6 9277.6 9310.0 9360.5 3203.6 3323.6 3470.5 3476.3 3620.0 884.8 946.5 947.8 995.1 1060.7 18515.0 18671.7 18835.9 19796.2 19997.5 948.7 970.3 984.2 1020.1 1047.3 8032.4 10739.6 10988.3 11145.4 11178.6 401080.0 410618.0 471719.0 482058.0 487103.0 89591.0 90491.5 91155.5 93185.5 97008.5 473.5 505.8 514.7 514.8 516.2 10515.7 10631.6 10940.9 11394.7 11433.7 42005.0 42456.6 42724.1 43071.4 43365.0 59008.4 59809.7 59826.4 60001.0 60405.7 8596.9 8736.7 8885.4 9018.7 9048.0 10063.7 13204.3 13805.0 13875.4 13958.7 11481.2 11788.8 11789.0 11795.5 11986.3 11807.5 11916.1 11972.9 11988.5 12271.3 11691.8 12021.3 12240.9 12277.6 12317.3 8790.2 8931.0 8942.5 8952.7 9018.4 9096.0 11550.4 11979.8 12067.0 12239.3 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#3] mootools.html Cycles:180 Average:5981.44 Median:4764.21 stddev:3583.64 (75.2%) stddev-sans-first:3577.24 17:47:02 INFO - PROCESS | 25462 | Values: 1412.7 1487.4 1528.4 1660.5 1819.7 8475.9 9185.7 9187.2 9444.8 9450.7 8765.3 8959.4 8969.4 8973.8 8975.3 2340.3 2375.4 2378.4 2378.6 2379.0 2349.4 2365.1 2423.4 2429.3 2429.9 2354.9 2365.6 2385.1 2390.5 2401.5 2306.6 2337.3 2341.4 2351.8 2364.9 2261.9 2317.5 2326.2 2326.4 2330.1 7823.4 8643.5 9152.4 9191.0 9203.0 5077.6 5322.9 5327.4 5353.0 5354.6 3336.5 3344.6 3416.2 3456.6 3458.8 1105.8 1127.2 1135.3 1144.1 1163.1 13442.2 13519.8 13522.3 13523.2 13532.1 4672.4 4763.8 4764.6 4844.2 4854.1 11169.2 11194.1 11196.4 11197.8 11204.3 5198.9 5205.1 5205.7 5208.7 5212.5 4775.7 4859.0 4917.0 5086.7 5090.6 3985.5 4035.7 4061.2 4067.1 4141.4 1162.9 1173.2 1185.4 1186.3 1220.3 826.5 903.2 905.2 908.8 917.4 5663.6 5687.5 5783.5 5916.9 5944.3 445.8 448.1 450.0 456.8 481.6 3353.6 3761.1 3902.1 3940.7 4028.8 11686.5 11750.4 11871.2 11881.9 11909.7 11241.0 11594.5 11608.4 11616.1 11624.5 8372.6 8453.6 8476.2 8590.1 8714.7 5893.9 5939.8 5967.2 6021.5 6048.1 10475.8 10696.4 10793.9 10820.8 10826.3 10772.7 10777.4 10997.0 11086.8 11209.7 7813.8 7844.1 7895.8 7907.1 7908.9 8827.2 8909.7 8914.1 8920.0 9114.8 7851.8 7896.6 7979.5 7986.9 8221.4 7889.8 7914.6 7954.3 8008.6 8142.7 6961.2 7037.2 7049.5 7050.4 7055.6 4886.7 4908.6 4973.9 4980.7 5019.4 5037.6 5080.8 5086.9 5168.9 5193.3 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#4] prototype.html Cycles:180 Average:4726.55 Median:5312.58 stddev:1386.63 (26.1%) stddev-sans-first:1350.95 17:47:02 INFO - PROCESS | 25462 | Values: 344.5 344.9 353.7 354.2 354.3 5414.9 5449.7 5489.6 5490.6 5498.2 5381.4 5383.8 5422.8 5439.3 5444.5 3886.4 4094.0 4233.3 4248.9 4261.2 4247.4 4259.7 4260.2 4261.3 4262.5 4075.9 4222.7 4228.5 4230.9 4241.0 4115.8 4252.7 4260.3 4270.9 4280.6 3751.0 3759.7 3767.8 3767.8 3798.4 5164.2 5231.7 5263.4 5274.2 5285.5 4943.7 4948.4 4956.6 4965.0 4977.4 5382.0 5517.4 5663.7 5668.7 5690.8 3995.9 4091.4 4093.9 4100.4 4119.7 5864.2 5877.2 5886.8 5900.5 5918.3 4020.7 4185.7 4214.5 4222.4 4229.3 4250.7 4267.6 4278.2 4288.1 4311.6 4842.2 4890.6 4920.6 4926.7 4934.7 5469.2 5662.9 5693.4 5740.3 5751.5 1462.1 1466.9 1467.0 1471.5 1473.0 929.7 936.8 948.9 954.8 955.5 4852.7 4920.5 4939.2 4945.9 4948.1 5202.4 5209.9 5223.6 5244.5 5245.5 4571.3 4886.4 4922.9 4928.2 4930.4 2734.0 2755.9 2762.9 2774.9 2795.7 5310.4 5338.6 5348.9 5360.8 5375.3 5314.7 5327.9 5508.8 5975.9 6027.5 5801.3 5803.5 6036.4 6058.5 6069.5 5875.1 5884.6 5900.4 5906.6 5927.3 5642.6 5815.6 5824.2 5829.3 5841.5 6001.2 6010.3 6031.4 6058.5 6070.5 4308.9 4333.7 4349.9 4366.0 4366.8 5649.0 5903.6 5903.9 5917.9 5944.7 5723.7 5729.5 5769.4 5775.3 5780.8 5571.9 5719.5 5735.4 5738.9 5761.5 5577.5 5608.9 5632.0 5633.7 5661.0 5743.3 5757.8 5785.9 5805.9 5815.9 5477.6 5644.3 5681.4 5687.5 5691.0 17:47:02 INFO - PROCESS | 25462 | 17:47:02 INFO - PROCESS | 25462 | [#5] yui.html Cycles:180 Average:15818.24 Median:4713.97 stddev:18627.20 (395.1%) stddev-sans-first:18644.64 17:47:02 INFO - PROCESS | 25462 | Values: 652.5 823.2 855.7 856.4 856.7 13525.3 14319.8 14453.3 14480.5 14525.3 14743.4 14761.3 14775.6 14784.1 14822.0 684.9 821.5 839.3 844.2 846.5 835.1 838.8 841.6 841.6 841.7 885.2 888.2 890.7 891.4 891.9 706.7 862.3 882.0 883.8 887.1 644.1 644.5 644.6 644.8 645.2 14430.4 14455.1 14462.9 14467.7 14482.5 13927.0 13966.7 14158.5 14178.5 14236.7 12037.2 12169.9 12181.1 12265.6 12349.9 4389.4 4447.4 4463.1 4528.5 4532.8 55067.0 55087.4 55143.0 55243.0 55354.7 10397.8 10441.8 10565.6 10632.9 10839.8 19606.1 19762.1 19792.5 20298.2 20336.4 7823.1 8106.2 8136.2 8424.0 8538.2 4615.7 4705.4 4722.6 4781.7 4881.7 3314.1 3410.0 3420.0 3422.1 3423.6 632.3 642.9 644.8 644.8 645.7 698.7 777.1 799.3 810.0 812.7 9440.7 9989.9 10020.5 10088.2 10101.6 607.5 714.0 766.7 786.2 787.2 4762.2 4788.9 4923.0 4967.4 4982.8 69015.0 70268.6 70283.0 70381.6 74949.0 52086.0 53340.5 61402.1 62834.7 63901.4 53074.9 53164.7 53646.2 54616.0 57007.4 9776.0 9817.9 9820.8 9990.4 10033.7 36748.8 37466.8 37918.8 38169.2 39335.7 49321.0 49662.5 49863.8 49895.8 50194.5 12646.3 12711.8 12830.4 12849.5 13166.8 13780.9 14187.6 14503.9 14662.3 15280.2 13419.1 13548.9 13639.7 14011.2 14306.8 13322.6 13444.9 13537.7 13958.6 14044.4 13293.8 13313.9 13538.4 13851.9 14083.0 10007.7 10127.9 10402.8 10539.5 10627.5 10737.5 11029.1 11047.7 11048.0 11171.6 17:47:02 INFO - PROCESS | 25462 | -------- Summary: end -------- 17:47:02 INFO - PROCESS | 25462 | 17:47:03 INFO - TEST-INFO | 25462: exit 0 17:47:03 INFO - TEST-OK | dromaeo_css | took 1113019ms 17:47:03 INFO - TEST-START | kraken 17:47:03 INFO - operating with platform_type : linux_ 17:47:03 INFO - Initialising browser for kraken test... 17:47:03 INFO - TEST-INFO | started process 25645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOF5ATq/profile http://localhost:41878/getInfo.html) 17:47:06 INFO - PROCESS | 25645 | __metrics Screen width/height:1600/1200 17:47:06 INFO - PROCESS | 25645 | colorDepth:24 17:47:06 INFO - PROCESS | 25645 | Browser inner width/height: 1024/697 17:47:06 INFO - PROCESS | 25645 | __metrics 17:47:07 ERROR - PROCESS | 25645 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:47:08 INFO - TEST-INFO | 25645: exit 0 17:47:08 INFO - Browser initialized. 17:47:08 INFO - Running cycle 1/1 for kraken test... 17:47:08 INFO - TEST-INFO | started process 25726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOF5ATq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:47:20 INFO - PROCESS | 25726 | RSS: Main: 152621056 17:47:20 INFO - PROCESS | 25726 | 17:47:22 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41878/tests/kraken/driver.html?testName=audio-beat-detection) 17:47:23 INFO - PROCESS | 25726 | RSS: Main: 208584704 17:47:23 INFO - PROCESS | 25726 | 17:47:26 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41878/tests/kraken/driver.html?testName=audio-dft) 17:47:27 INFO - PROCESS | 25726 | RSS: Main: 196870144 17:47:27 INFO - PROCESS | 25726 | 17:47:30 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41878/tests/kraken/driver.html?testName=audio-fft) 17:47:30 INFO - PROCESS | 25726 | RSS: Main: 264712192 17:47:30 INFO - PROCESS | 25726 | 17:47:32 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41878/tests/kraken/driver.html?testName=audio-oscillator) 17:47:33 INFO - PROCESS | 25726 | RSS: Main: 173953024 17:47:33 INFO - PROCESS | 25726 | 17:47:35 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41878/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:47:36 INFO - PROCESS | 25726 | RSS: Main: 178290688 17:47:36 INFO - PROCESS | 25726 | 17:47:39 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41878/tests/kraken/driver.html?testName=imaging-darkroom) 17:47:40 INFO - PROCESS | 25726 | RSS: Main: 214429696 17:47:40 INFO - PROCESS | 25726 | 17:47:43 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41878/tests/kraken/driver.html?testName=imaging-desaturate) 17:47:44 INFO - PROCESS | 25726 | RSS: Main: 212279296 17:47:44 INFO - PROCESS | 25726 | 17:47:47 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41878/tests/kraken/driver.html?testName=json-parse-financial) 17:47:47 INFO - PROCESS | 25726 | RSS: Main: 213639168 17:47:47 INFO - PROCESS | 25726 | 17:47:49 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41878/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:47:50 INFO - PROCESS | 25726 | RSS: Main: 201539584 17:47:50 INFO - PROCESS | 25726 | 17:47:51 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:47:52 INFO - PROCESS | 25726 | RSS: Main: 206118912 17:47:52 INFO - PROCESS | 25726 | 17:47:54 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:47:55 INFO - PROCESS | 25726 | RSS: Main: 215293952 17:47:55 INFO - PROCESS | 25726 | 17:47:57 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:47:58 INFO - PROCESS | 25726 | RSS: Main: 224059392 17:47:58 INFO - PROCESS | 25726 | 17:48:01 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:48:01 INFO - PROCESS | 25726 | RSS: Main: 219275264 17:48:01 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | Cycle 1(1): loaded http://localhost:41878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41878/tests/kraken/driver.html?testName=ai-astar) 17:48:03 INFO - PROCESS | 25726 | RSS: Main: 265297920 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | __start_tp_report 17:48:03 INFO - PROCESS | 25726 | _x_x_mozilla_page_load 17:48:03 INFO - PROCESS | 25726 | _x_x_mozilla_page_load_details 17:48:03 INFO - PROCESS | 25726 | |i|pagename|runs| 17:48:03 INFO - PROCESS | 25726 | |0;ai-astar;109;105;110;111;110;108;109;109;110;110 17:48:03 INFO - PROCESS | 25726 | |1;audio-beat-detection;147;122;151;205;130;157;156;131;181;133 17:48:03 INFO - PROCESS | 25726 | |2;audio-dft;161;161;161;162;160;159;160;160;161;161 17:48:03 INFO - PROCESS | 25726 | |3;audio-fft;73;75;74;136;75;74;75;73;96;74 17:48:03 INFO - PROCESS | 25726 | |4;audio-oscillator;91;91;145;91;91;91;126;90;91;116 17:48:03 INFO - PROCESS | 25726 | |5;imaging-gaussian-blur;116;116;116;118;117;117;117;117;117;116 17:48:03 INFO - PROCESS | 25726 | |6;imaging-darkroom;145;146;147;146;145;145;145;145;145;145 17:48:03 INFO - PROCESS | 25726 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;96;94 17:48:03 INFO - PROCESS | 25726 | |8;json-parse-financial;56;56;58;56;58;57;56;115;54;55 17:48:03 INFO - PROCESS | 25726 | |9;json-stringify-tinderbox;60;61;59;59;60;60;60;60;60;60 17:48:03 INFO - PROCESS | 25726 | |10;stanford-crypto-aes;83;78;78;77;78;80;79;79;80;79 17:48:03 INFO - PROCESS | 25726 | |11;stanford-crypto-ccm;128;127;119;123;120;121;124;126;189;124 17:48:03 INFO - PROCESS | 25726 | |12;stanford-crypto-pbkdf2;164;162;161;160;161;161;161;159;159;161 17:48:03 INFO - PROCESS | 25726 | |13;stanford-crypto-sha256-iterative;63;62;66;63;62;63;64;64;61;61 17:48:03 INFO - PROCESS | 25726 | __end_tp_report 17:48:03 INFO - PROCESS | 25726 | __start_cc_report 17:48:03 INFO - PROCESS | 25726 | _x_x_mozilla_cycle_collect,1847 17:48:03 INFO - PROCESS | 25726 | __end_cc_report 17:48:03 INFO - PROCESS | 25726 | __startTimestamp1463446083915__endTimestamp 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | ------- Summary: start ------- 17:48:03 INFO - PROCESS | 25726 | Number of tests: 14 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:1.66 (1.5%) stddev-sans-first:1.76 17:48:03 INFO - PROCESS | 25726 | Values: 109.0 105.0 110.0 111.0 110.0 108.0 109.0 109.0 110.0 110.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:153.50 stddev:25.60 (16.7%) stddev-sans-first:27.11 17:48:03 INFO - PROCESS | 25726 | Values: 147.0 122.0 151.0 205.0 130.0 157.0 156.0 131.0 181.0 133.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 17:48:03 INFO - PROCESS | 25726 | Values: 161.0 161.0 161.0 162.0 160.0 159.0 160.0 160.0 161.0 161.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#3] audio-fft Cycles:10 Average:82.50 Median:74.50 stddev:20.03 (26.9%) stddev-sans-first:20.95 17:48:03 INFO - PROCESS | 25726 | Values: 73.0 75.0 74.0 136.0 75.0 74.0 75.0 73.0 96.0 74.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:91.00 stddev:19.69 (21.6%) stddev-sans-first:20.46 17:48:03 INFO - PROCESS | 25726 | Values: 91.0 91.0 145.0 91.0 91.0 91.0 126.0 90.0 91.0 116.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 17:48:03 INFO - PROCESS | 25726 | Values: 116.0 116.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 116.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 17:48:03 INFO - PROCESS | 25726 | Values: 145.0 146.0 147.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 17:48:03 INFO - PROCESS | 25726 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:56.00 stddev:18.63 (33.3%) stddev-sans-first:19.63 17:48:03 INFO - PROCESS | 25726 | Values: 56.0 56.0 58.0 56.0 58.0 57.0 56.0 115.0 54.0 55.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 17:48:03 INFO - PROCESS | 25726 | Values: 60.0 61.0 59.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:1.66 (2.1%) stddev-sans-first:1.00 17:48:03 INFO - PROCESS | 25726 | Values: 83.0 78.0 78.0 77.0 78.0 80.0 79.0 79.0 80.0 79.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:125.00 stddev:20.90 (16.7%) stddev-sans-first:22.16 17:48:03 INFO - PROCESS | 25726 | Values: 128.0 127.0 119.0 123.0 120.0 121.0 124.0 126.0 189.0 124.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:1.45 (0.9%) stddev-sans-first:1.01 17:48:03 INFO - PROCESS | 25726 | Values: 164.0 162.0 161.0 160.0 161.0 161.0 161.0 159.0 159.0 161.0 17:48:03 INFO - PROCESS | 25726 | 17:48:03 INFO - PROCESS | 25726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.52 (2.4%) stddev-sans-first:1.62 17:48:03 INFO - PROCESS | 25726 | Values: 63.0 62.0 66.0 63.0 62.0 63.0 64.0 64.0 61.0 61.0 17:48:03 INFO - PROCESS | 25726 | -------- Summary: end -------- 17:48:03 INFO - PROCESS | 25726 | 17:48:04 INFO - TEST-INFO | 25726: exit 0 17:48:04 INFO - TEST-OK | kraken | took 61783ms 17:48:04 INFO - SUITE-END | took 1174s 17:48:05 INFO - Completed test suite (00:19:34) 17:48:05 INFO - javascript benchmark 17:48:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.205198700325, 3186.745060395167, 3210.1814037420445, 3223.306988997365, 3253.8698452061917, 5275.894482110355, 5345.572354211664, 5395.190721391793, 5460.453954604538, 5516.034693928562, 5391.110466772988, 5435.42927992561, 5448.318960129984, 5456.427075157111, 5506.256655351526, 4906.043321552299, 4921.064997650435, 4929.186684197121, 5021.774020169101, 5033.194688849785, 4966.726830024347, 5059.139946209153, 5076.289319495274, 5082.79668813247, 5091.516305950929, 4861.9027619447415, 4884.658073934826, 4982.0, 4983.95336979233, 5034.144195486776, 4914.11545921734, 4937.580305674022, 4942.456329803718, 4945.208766597327, 4950.925736113961, 4842.297866809293, 4845.176320025604, 4925.605951523869, 4926.975365123188, 4935.703857768545, 5265.078611243017, 5295.814646487393, 5342.839714808562, 5365.275687782138, 5393.217983392426, 5800.564957628195, 5859.033259512175, 5864.912026319608, 5866.6186697864505, 5868.2371291732225, 5676.233708449389, 5787.594868359217, 5857.209276747627, 5874.588778785486, 5929.940700592982, 5429.185622156708, 5474.39781624025, 5505.752241149158, 5525.972370138164, 5526.668399896019, 5921.73352199152, 5950.137230101611, 5951.642901425928, 5997.310309314397, 6006.279246490449, 5670.121131224667, 5706.9714651426475, 5740.799072032459, 5777.162311464814, 5783.190353350535, 5855.092460668603, 5860.443257890579, 5861.941380586225, 5863.67749773758, 5910.527157827363, 5821.446962538636, 5833.125031245348, 5834.27071616048, 5838.67887266196, 5842.678652674059, 5681.119426488901, 5721.141828725641, 5797.652140871562, 5843.152742852262, 5852.590318677651, 4395.868123956287, 4444.200043992112, 4445.488768791565, 4470.0, 4483.618892394118, 3922.7646341219047, 3928.3125453046173, 3950.841966321315, 3966.5240171179644, 3982.2234664240195, 5278.656887302313, 5438.0, 5490.1764735289225, 5541.0580201365865, 5595.720213989365, 5849.415058494202, 5857.648541087548, 5862.179294898718, 5881.764729410861, 5918.704064796743, 5472.26124473199, 5485.204645326406, 5491.972540137273, 5498.340199176125, 5507.614466987273, 4873.829415970495, 4961.652684312064, 4972.030454061423, 4994.225894986283, 5017.197248440232, 6001.489873360726, 6012.729427175787, 6023.879522409527, 6024.759009639653, 6033.215681961405, 5840.2115714378015, 5856.853578660568, 5868.413158684098, 5871.266091738533, 5885.028970219866, 5824.825255242349, 5933.881322373615, 5950.047992321208, 5978.282606087298, 6029.879402412015, 5714.599978001666, 5751.683657398883, 5759.136129580596, 5761.308642962871, 5764.3371012332445, 5655.7454914528125, 5847.093700476433, 5849.181114644039, 5857.677827719346, 5879.794207202727, 5687.544996400361, 5707.4577915098, 5735.053716136791, 5750.108733146369, 5770.1344798278915, 5689.317281926195, 5733.770649174153, 5736.741846616996, 5740.167675687086, 5759.452851979056, 5450.454954504517, 5551.972240138935, 5653.491185793298, 5654.180143879197, 5666.660000399989, 5624.7187640616985, 5668.829935101953, 5707.286589176353, 5722.4277572242445, 5845.035569131216, 5588.273524441796, 5623.409541998195, 5631.493165615114, 5638.210650509014, 5639.774409023593], "value": 5369.176573254194, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.7322251431884, 640.2285246278235, 649.5063751548824, 653.1019847709399, 656.1207981305054, 32440.0, 36916.44625330619, 37317.253654926884, 37471.25057498848, 40238.798806006, 46466.0, 46535.53464465354, 46570.30144547835, 46705.299420508716, 46791.29813052798, 7907.0, 8939.240164586, 9078.773030674252, 9081.863772043425, 9870.753231169207, 10391.792164156712, 10803.081738052275, 10810.189235807307, 10816.78366432671, 10826.133909287277, 10857.728556786065, 10893.291936024134, 10912.618058367958, 10913.454327283644, 10916.617918372858, 8146.918530814675, 8291.461055031403, 8317.916820831775, 10538.367697938149, 10817.783644327108, 10416.843747343795, 10803.945980270048, 10804.0816530595, 10810.189235807346, 10829.945850270777, 42489.36265956013, 42915.35626965567, 42923.78538107289, 42933.78533107346, 42946.355804662955, 3380.3239352129426, 3467.9653203468147, 3527.4532447470683, 3681.742278040538, 3788.0, 31687.366252674816, 32075.6792432075, 32631.18422039468, 33421.33157336864, 33839.323213535594, 4316.654667626613, 4388.5830846069575, 4393.476803931777, 4405.779711014468, 4539.568740969636, 358896.0, 359127.0, 359328.0, 359663.9999999974, 360989.0, 35432.0, 59563.70218148968, 59976.0, 59992.0, 60056.699716501134, 34842.82578587091, 36546.0, 36612.633873661456, 36620.26759464796, 36646.450303245474, 14553.490627827976, 14671.413143474141, 14704.852951470562, 14706.705865882623, 14707.264636768332, 11431.657050288504, 12097.57658481949, 12302.138850280395, 12334.444949977273, 12590.992720582331, 8636.309095272363, 8684.43551169172, 8693.956530217309, 8706.477611343213, 8755.693550725695, 3314.237725323141, 3346.096553930474, 3363.5291059251726, 3371.9662803371652, 3388.9152771180425, 1011.7672935224939, 1044.9863632276679, 1060.2843080920502, 1115.4311301236266, 1200.9159358844797, 33294.33411331808, 33499.49750753741, 33916.32167356639, 33921.83039084838, 34430.82784586111, 1082.6914329416236, 1153.9307641541534, 1166.8133098704166, 1199.6640940536663, 1211.987880121205, 18011.549711257063, 18051.729224061648, 18121.275148994155, 18426.447206583824, 18429.078546072644, 576697.0, 590018.0, 593606.0, 598481.0, 600132.0, 59366.70316648475, 60433.0, 60649.0, 60681.39318606846, 60695.0, 1263.7409331087106, 1286.4854058376536, 1291.1349395904767, 1340.1155237543176, 1374.8006539051783, 25592.616110758725, 33090.33819323552, 33287.334253314315, 35640.82179589086, 35803.00892342561, 56416.717916410154, 57074.0, 57108.71445642745, 57194.42805571891, 57200.42799571951, 491697.0, 492016.0, 495709.0, 495872.0, 497000.0, 25443.87278063672, 25961.79324757417, 25985.610215847155, 28721.42557148887, 29187.270318241364, 48007.75996119997, 50480.74759626178, 51975.48024519706, 53509.46490535045, 53572.732136339066, 40691.0, 43184.0, 43863.34204986991, 43871.34192987044, 44302.778486108655, 42253.0, 43189.7840510808, 43221.351679725456, 43420.348694768974, 43861.342079869464, 42923.57076429196, 43202.35196471993, 43588.78205608952, 43646.781766090964, 44190.0, 35412.645873541966, 37949.0, 38665.0, 38819.80590097144, 39119.41320880246, 29205.707942921148, 29318.7068129316, 29332.0, 29576.0, 29596.704032959395], "value": 19706.545670368505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.3156308024039, 396.84126349460223, 397.13226599879283, 398.6750798099549, 414.63097842919785, 18361.265549378026, 19791.604167916652, 19809.702854457177, 19877.105530251138, 20517.589648207064, 17111.74332385012, 17783.28866845328, 18023.549411264692, 19634.0, 19656.0, 5788.913166302498, 5809.477147056764, 5916.940830591703, 5922.526197904157, 5977.073553599199, 5756.0, 5844.853878653025, 5895.734691938873, 5907.291125064999, 5913.9112913306335, 5756.222909907172, 5859.209006784094, 5893.233879595668, 5929.851753706149, 5940.168376427304, 5771.826845194651, 5780.7687692492245, 5835.79574714883, 5841.941580584183, 5875.676837773921, 5128.512791284822, 5236.738163091868, 5267.710275934822, 5271.235670827747, 5273.604479664041, 22760.658590121162, 23109.0, 23144.3056708299, 23312.65031024536, 23499.882500587388, 2179.869207847534, 2305.72331320241, 2334.2180369576213, 2336.123953517431, 2377.964330535026, 10609.257351985365, 10650.680479585624, 10774.838377424345, 12662.746745065138, 14660.9004324675, 1981.4749091490767, 1991.2632326039459, 1991.7750583391255, 2076.6158260721813, 2109.7847260376543, 65399.0, 78685.60657196735, 81117.0, 81793.59103204505, 82999.5850020746, 10165.390076595428, 10207.795844083077, 10822.729431764268, 10976.286541374786, 11178.105751539857, 14529.0, 15431.228438578026, 15469.690606187813, 15932.203389830462, 15959.601009974844, 8087.797805054803, 9632.855507167398, 9820.361676491006, 10015.933081059433, 10278.280520363653, 8242.835143297101, 8407.62165702545, 8507.53208573522, 8535.658573656985, 8561.186687264824, 8718.517851965182, 8993.595288212045, 9277.58250878712, 9310.0, 9360.485173315534, 3203.583534140593, 3323.567936168283, 3470.4967779671806, 3476.322117187124, 3619.9819000904827, 884.8407286688329, 946.4510583861344, 947.8436058050482, 995.0795514149382, 1060.6870973062964, 18515.0, 18671.692981491437, 18835.90582047081, 19796.208151674058, 19997.500062498555, 948.6679662118204, 970.3304719743354, 984.2421335571713, 1020.1430798129607, 1047.2826114111858, 8032.357411407073, 10739.570417183382, 10988.340699558052, 11145.442727863574, 11178.552857885594, 401080.0, 410618.0, 471719.0, 482058.0, 487103.0, 89591.0, 90491.54754226319, 91155.54422227846, 93185.53407233056, 97008.51495742617, 473.51701264710493, 505.83307508522097, 514.73233918363, 514.8429728932637, 516.2488579117451, 10515.737106572396, 10631.627893023708, 10940.945295273472, 11394.658160255205, 11433.714157146138, 42005.0, 42456.5754342465, 42724.145517088866, 43071.35392969171, 43365.0, 59008.409915900294, 59809.70095149496, 59826.401735983825, 60001.0, 60405.697971509864, 8596.91403085961, 8736.694215702673, 8885.37802353855, 9018.729438116867, 9048.0, 10063.69808905734, 13204.339783010619, 13805.0, 13875.444982200599, 13958.651033724238, 11481.196316258116, 11788.76422471563, 11789.0, 11795.52817887275, 11986.280823150639, 11807.468663909973, 11916.106292028306, 11972.940135299616, 11988.520459181884, 12271.263724176579, 11691.766164676828, 12021.278723276631, 12240.877591223974, 12277.570285040338, 12317.260964342167, 8790.208881200722, 8930.955345223232, 8942.50816205115, 8952.731418057469, 9018.413803102778, 9096.0, 11550.364729939598, 11979.76040479203, 12067.0, 12239.32683702405], "value": 9339.025777326391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.6750847305113, 1487.4273404739172, 1528.4268399350244, 1660.4908757669941, 1819.7270409438584, 8475.91524084759, 9185.678501252458, 9187.173154416101, 9444.811103777938, 9450.66922657707, 8765.342599305062, 8959.37284390093, 8969.416987895782, 8973.82052358954, 8975.281977441806, 2340.32130682102, 2375.406148462884, 2378.357843382286, 2378.595638741418, 2378.9999999999914, 2349.400902769791, 2365.1012615206196, 2423.4062654649633, 2429.2712186344115, 2429.9271021869286, 2354.858708477488, 2365.6215005599106, 2385.129427758864, 2390.5457962987066, 2401.4836810085826, 2306.642470417088, 2337.31049340445, 2341.426350544119, 2351.7530659280787, 2364.893579788905, 2261.9208327708616, 2317.5249073939804, 2326.220716060122, 2326.430024643956, 2330.0563271874917, 7823.413244006722, 8643.481391116491, 9152.359334846564, 9191.0, 9203.033681463483, 5077.5684066854365, 5322.946770532284, 5327.360716714019, 5352.973235133838, 5354.625176237665, 3336.5495658086334, 3344.6320904700706, 3416.214270717724, 3456.6370531094376, 3458.775179613342, 1105.7512059786563, 1127.1771606727134, 1135.3017893995216, 1144.1476100305326, 1163.139276935062, 13442.193468391928, 13519.797203041962, 13522.256275904821, 13523.188608683511, 13532.052756307063, 4672.369230153954, 4763.809447622057, 4764.618830493552, 4844.2007068833955, 4854.077725232195, 11169.218154729091, 11194.10447164225, 11196.440177991231, 11197.832032519518, 11204.327740335606, 5198.92201616976, 5205.06308864408, 5205.687658740488, 5208.739563021833, 5212.452692467312, 4775.6657034007285, 4858.951410485921, 4917.0, 5086.669366491166, 5090.618203634668, 3985.5416627088257, 4035.6569691575955, 4061.2283666103344, 4067.1458993611673, 4141.420201171778, 1162.8953394194564, 1173.196360493065, 1185.4309931233056, 1186.258588382261, 1220.2922305063041, 826.5040975414823, 903.2367649336301, 905.158202871336, 908.822779557993, 917.4495302818256, 5663.631863928831, 5687.51656109227, 5783.508401785895, 5916.911246331309, 5944.256967879015, 445.82835608290395, 448.12615399969536, 449.99100017999456, 456.75563573488023, 481.63877092180866, 3353.5975682918206, 3761.055358684997, 3902.1415288636454, 3940.6847452203756, 4028.778417187083, 11686.532538698528, 11750.353730544732, 11871.169018168644, 11881.88118118825, 11909.702257443632, 11241.0, 11594.536218551164, 11608.419579021016, 11616.070714342832, 11624.476898539586, 8372.623231954576, 8453.619587118594, 8476.152384761475, 8590.098039705867, 8714.738557843275, 5893.852653683693, 5939.821805345847, 5967.194428752063, 6021.488173505301, 6048.0625503047095, 10475.842862356918, 10696.358218506915, 10793.892061079288, 10820.837687434852, 10826.296290741077, 10772.676819695422, 10777.407242601419, 10997.0, 11086.833697494383, 11209.663710088711, 7813.765587032398, 7844.137144914169, 7895.84208315819, 7907.130215676386, 7908.8813667793875, 8827.24968377695, 8909.73270801877, 8914.064023277722, 8920.0, 9114.817703646022, 7851.764447066597, 7896.605169741375, 7979.521228726295, 7986.8801967971685, 8221.383396245425, 7889.842203156019, 7914.572240940575, 7954.284114429729, 8008.559529225716, 8142.715004974797, 6961.164660240601, 7037.225905150327, 7049.471289653194, 7050.365467107985, 7055.61194134307, 4886.682365646222, 4908.558229759339, 4973.900521989613, 4980.676256043345, 5019.422766381766, 5037.608524268242, 5080.796768129381, 5086.872828179326, 5168.870778230575, 5193.298904647824], "value": 4627.249674467813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.5107946715664, 344.8810160494629, 353.66755250064955, 354.2472246476238, 354.2613650538627, 5414.918776218355, 5449.673019618821, 5489.560835133189, 5490.588205884555, 5498.1752737089455, 5381.354237491497, 5383.757730902109, 5422.8069824638615, 5439.3200849893765, 5444.50999410053, 3886.378179491268, 4094.0379010932484, 4233.343831706091, 4248.893777655552, 4261.211675839964, 4247.447831781864, 4259.723117997336, 4260.211860805761, 4261.296886013804, 4262.46719160105, 4075.8981025474454, 4222.662187025046, 4228.450301460805, 4230.936535951963, 4241.0, 4115.814788334532, 4252.744835309892, 4260.297050986584, 4270.850520231777, 4280.550542193056, 3750.955729127632, 3759.7368184227116, 3767.755095918784, 3767.830447629836, 3798.4112462568346, 5164.2253661950635, 5231.686098834082, 5263.4473380295285, 5274.235235890813, 5285.497877701612, 4943.678660887031, 4948.430930443009, 4956.6282528810125, 4965.0, 4977.352944117278, 5381.973090134564, 5517.420670829545, 5663.7168141593165, 5668.659880407189, 5690.772369105231, 3995.862213715648, 4091.4272001919753, 4093.9385909211387, 4100.36444351126, 4119.711620186588, 5864.237649105632, 5877.235959325303, 5886.793962211302, 5900.527957763412, 5918.348981612019, 4020.718549701493, 4185.686073544496, 4214.473190851144, 4222.4299719538085, 4229.281022226229, 4250.723702959298, 4267.615914567704, 4278.187144442546, 4288.099499105223, 4311.555628864366, 4842.18418093215, 4890.608751299887, 4920.581750551171, 4926.679765815211, 4934.69230653877, 5469.206964990054, 5662.886742265132, 5693.402192769782, 5740.282464691913, 5751.4823665869435, 1462.1227263641942, 1466.8826493880465, 1467.0464198271209, 1471.4776254429737, 1473.042522360453, 929.7303781903172, 936.7611259128879, 948.8624149498285, 954.7756277274835, 955.5365647660878, 4852.684575502581, 4920.458749537548, 4939.234418665113, 4945.876353091202, 4948.134076536592, 5202.401723801736, 5209.921851172236, 5223.634345595772, 5244.475552444725, 5245.4888795635725, 4571.337156112345, 4886.436340371119, 4922.852314430573, 4928.162212423897, 4930.359053323117, 2734.002089237414, 2755.9448811024067, 2762.9447411051665, 2774.8751306190866, 2795.68602756704, 5310.415854256029, 5338.572914166857, 5348.946510534923, 5360.839174824683, 5375.2854808321545, 5314.73426328682, 5327.893442131136, 5508.807191748269, 5975.850603734854, 6027.457528822427, 5801.274840644919, 5803.47768700819, 6036.434515330967, 6058.485028772516, 6069.453749162597, 5875.148103525051, 5884.6469211847425, 5900.380460051804, 5906.645601263938, 5927.318358388668, 5642.633228840113, 5815.563832712648, 5824.184614153937, 5829.329627092939, 5841.474267315962, 6001.159837622824, 6010.278766548042, 6031.431827724736, 6058.455448711464, 6070.48400885912, 4308.935365969451, 4333.674974376871, 4349.934750978801, 4365.978170109129, 4366.847160349373, 5648.971755141198, 5903.586748927534, 5903.852403689943, 5917.940820591911, 5944.702764861826, 5723.6851973141875, 5729.54163666914, 5769.3942136076785, 5775.306963164448, 5780.768769249351, 5571.8885622288135, 5719.456651618089, 5735.36910939788, 5738.885222295614, 5761.5102716269585, 5577.470140336662, 5608.85977850557, 5631.971840140773, 5633.690147041952, 5661.0, 5743.310802703702, 5757.827265182052, 5785.9132113017495, 5805.883882322245, 5815.94184058154, 5477.561795056306, 5644.266245388238, 5681.431856814285, 5687.459691329317, 5690.971545142248], "value": 4288.031947774145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.451940370089, 823.1768231768232, 855.6534603485586, 856.4090777363618, 856.695872965097, 13525.256110913895, 14319.785203221947, 14453.277336133178, 14480.493182738606, 14525.346359413812, 14743.410263589443, 14761.335739891703, 14775.556733298019, 14784.112953222813, 14822.036567623098, 684.934931181537, 821.4906757810166, 839.2866063845728, 844.2486187293314, 846.4878748357247, 835.1439774231432, 838.7609531283586, 841.5539763925129, 841.6338892581747, 841.7432683031673, 885.2165833237567, 888.2006194425037, 890.7105190812979, 891.4383938118976, 891.8929728432597, 706.6890568149997, 862.3144600042981, 881.9955900220522, 883.8453270677605, 887.104024934814, 644.0950464597248, 644.5423749138071, 644.6390021587926, 644.8097811145723, 645.2192846655553, 14430.350634221486, 14455.060421072596, 14462.855371446256, 14467.710645787025, 14482.493112741004, 13927.0, 13966.650833729136, 14158.50445234412, 14178.50375236872, 14236.715265694627, 12037.157398982075, 12169.878301216962, 12181.147319687625, 12265.632031039084, 12349.93825030878, 4389.429374181368, 4447.444069491314, 4463.129689710508, 4528.547145285478, 4532.750698711569, 55067.0, 55087.44912550903, 55143.0, 55243.0, 55354.72322638361, 10397.792044159074, 10441.843372349269, 10565.577376904992, 10632.89367106319, 10839.83740243897, 19606.117724702424, 19762.11070501831, 19792.50518737014, 20298.18807247723, 20336.389908302775, 7823.100343460461, 8106.2299081588335, 8136.186381361935, 8424.03123640789, 8538.188872057144, 4615.723056616614, 4705.364775755229, 4722.574968252874, 4781.665283430196, 4881.658283920163, 3314.105191598255, 3409.9659003410143, 3420.025292791592, 3422.0760394693793, 3423.630547780858, 632.3486808587163, 642.9453496452854, 644.78399736088, 644.7839973608893, 645.7481582182952, 698.664640972326, 777.1296148313812, 799.2646764976236, 809.9757007289791, 812.7399232245743, 9440.716778496657, 9989.900100998897, 10020.549075291628, 10088.243381746399, 10101.595936162472, 607.490065109453, 714.0075295339482, 766.6856588798678, 786.1705900275265, 787.1970589998169, 4762.190427627243, 4788.856334309976, 4922.9753851231235, 4967.428745694219, 4982.77577509013, 69015.0, 70268.64865675638, 70283.0, 70381.64809175921, 74949.0, 52086.0, 53340.46659533355, 61402.078968815506, 62834.685826570574, 63901.36098638954, 53074.87700492014, 53164.73417632964, 53646.195307070426, 54616.0, 57007.42992570021, 9775.969429677323, 9817.901820981984, 9820.754481138028, 9990.436913513975, 10033.749156271151, 36748.81625591855, 37466.81266593649, 37918.81040594779, 38169.23661526809, 39335.74108069252, 49321.0, 49662.50337496579, 49863.75068124781, 49895.75052124716, 50194.49805501898, 12646.30445325516, 12711.80932285998, 12830.358482075671, 12849.486020559447, 13166.802497962346, 13780.862191377957, 14187.574372768833, 14503.92748036253, 14662.266886655838, 15280.15959122259, 13419.127756695783, 13548.932255338661, 13639.727205456034, 14011.229382384066, 14306.785398219243, 13322.600321990356, 13444.865551344361, 13537.729245415234, 13958.58124256274, 14044.368003439666, 13293.800592991307, 13313.933430332787, 13538.390772415069, 13851.86148138546, 14083.014189006672, 10007.699769006942, 10127.949360253151, 10402.786871317128, 10539.473026348805, 10627.521761520904, 10737.46312684347, 11029.117670586173, 11047.668569942914, 11048.0, 11171.608993685182], "value": 7077.509228596749, "unit": "score"}], "name": "dromaeo_css", "value": 7195.38695346088, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 110.0, 111.0, 110.0, 108.0, 109.0, 109.0, 110.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 151.0, 205.0, 130.0, 157.0, 156.0, 131.0, 181.0, 133.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 160.0, 159.0, 160.0, 160.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 136.0, 75.0, 74.0, 75.0, 73.0, 96.0, 74.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 145.0, 91.0, 91.0, 91.0, 126.0, 90.0, 91.0, 116.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 56.0, 58.0, 57.0, 56.0, 115.0, 54.0, 55.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 78.0, 77.0, 78.0, 80.0, 79.0, 79.0, 80.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 119.0, 123.0, 120.0, 121.0, 124.0, 126.0, 189.0, 124.0], "value": 130.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0, 159.0, 159.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 66.0, 63.0, 62.0, 63.0, 64.0, 64.0, 61.0, 61.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1517.8, "alertThreshold": 2.0}]} 17:48:05 INFO - Return code: 0 17:48:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:48:05 ERROR - # TBPL SUCCESS # 17:48:05 INFO - Running post-action listener: _resource_record_post_action 17:48:05 INFO - ##### 17:48:05 INFO - ##### Finished run-tests step (success) 17:48:05 INFO - ##### 17:48:05 INFO - Running post-run listener: _resource_record_post_run 17:48:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 543080448; Read time: 12; Write time: 450024 17:48:05 INFO - TinderboxPrint: CPU usage
13.1% 17:48:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:48:05 INFO - TinderboxPrint: I/O write bytes / time
543,080,448 / 450,024 17:48:05 INFO - TinderboxPrint: CPU idle
8,271.2 (86.9%) 17:48:05 INFO - TinderboxPrint: CPU user
1,205.5 (12.7%) 17:48:05 INFO - TinderboxPrint: Swap in / out
0 / 0 17:48:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 255979520; Read time: 0; Write time: 223160 17:48:05 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287100928; Read time: 12; Write time: 226864 17:48:05 INFO - Running post-run listener: _upload_blobber_files 17:48:05 INFO - Blob upload gear active. 17:48:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:48:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:48:06 INFO - (blobuploader) - INFO - Open directory for files ... 17:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:07 INFO - (blobuploader) - INFO - Done attempting. 17:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:48:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:12 INFO - (blobuploader) - INFO - Done attempting. 17:48:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:48:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:13 INFO - (blobuploader) - INFO - Done attempting. 17:48:13 INFO - (blobuploader) - INFO - Iteration through files over. 17:48:13 INFO - Return code: 0 17:48:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:48:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:48:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d447d3b9bd080b165cdbaa99e5552dfb96830ed116a9addc2f41bbcf6a9994dedae745c0942fc14432b4d6acff16bf07dadccc8e9a7c2e2afb72bd883fc0ea3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c55213c92082fc93d60e3dc20806bf17bf0478447b9e22fe34dee2f70ba46d37b8de518bfee312919637e1160ef93a321092ff965cd37d227d4dd3a73eed7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/946480f680eacdd065849881c04f28d57465fa749826b3a6fe66ca2626cb2683deb90adc4c0328493e2f4d7a4d3d1e90b473f3b40068646243863152a66f9d56"} 17:48:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:48:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:48:13 INFO - Contents: 17:48:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d447d3b9bd080b165cdbaa99e5552dfb96830ed116a9addc2f41bbcf6a9994dedae745c0942fc14432b4d6acff16bf07dadccc8e9a7c2e2afb72bd883fc0ea3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c55213c92082fc93d60e3dc20806bf17bf0478447b9e22fe34dee2f70ba46d37b8de518bfee312919637e1160ef93a321092ff965cd37d227d4dd3a73eed7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/946480f680eacdd065849881c04f28d57465fa749826b3a6fe66ca2626cb2683deb90adc4c0328493e2f4d7a4d3d1e90b473f3b40068646243863152a66f9d56"} 17:48:13 INFO - Running post-run listener: copy_logs_to_upload_dir 17:48:13 INFO - Copying logs to upload dir... 17:48:13 INFO - mkdir: /builds/slave/test/build/upload/logs 17:48:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.945681 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-05-16 17:48:13.171861) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 17:48:13.176043) ========= 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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 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/5d447d3b9bd080b165cdbaa99e5552dfb96830ed116a9addc2f41bbcf6a9994dedae745c0942fc14432b4d6acff16bf07dadccc8e9a7c2e2afb72bd883fc0ea3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c55213c92082fc93d60e3dc20806bf17bf0478447b9e22fe34dee2f70ba46d37b8de518bfee312919637e1160ef93a321092ff965cd37d227d4dd3a73eed7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/946480f680eacdd065849881c04f28d57465fa749826b3a6fe66ca2626cb2683deb90adc4c0328493e2f4d7a4d3d1e90b473f3b40068646243863152a66f9d56"} build_url:https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/WOe0hKRJSUayknvFhm586A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d447d3b9bd080b165cdbaa99e5552dfb96830ed116a9addc2f41bbcf6a9994dedae745c0942fc14432b4d6acff16bf07dadccc8e9a7c2e2afb72bd883fc0ea3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c55213c92082fc93d60e3dc20806bf17bf0478447b9e22fe34dee2f70ba46d37b8de518bfee312919637e1160ef93a321092ff965cd37d227d4dd3a73eed7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/946480f680eacdd065849881c04f28d57465fa749826b3a6fe66ca2626cb2683deb90adc4c0328493e2f4d7a4d3d1e90b473f3b40068646243863152a66f9d56"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 17:48:13.201527) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:48:13.201904) ========= 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=6dbc10da6d83fee962c624be000001a8-1463444855.685957-466052728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 17:48:13.287976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 17:48:13.288383) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 17:48:13.288740) ========= ========= Total master_lag: 0.18 =========