builder: larch_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1463803037.82 results: success (0) buildid: 20160520183008 builduid: 785bde8eac2a4f799a9bdfa199129e9c revision: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:17.822590) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:17.822963) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:17.823238) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:17.882917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:17.883207) ========= 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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 20:57:17-- 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% 222M=0s 2016-05-20 20:57:18 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162747 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.060594) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.060880) ========= 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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013542 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.110028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.110343) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 894f88d5f1062eb50ac67f4abd48f54d0ee8775b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 894f88d5f1062eb50ac67f4abd48f54d0ee8775b --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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 20:57:18,123 truncating revision to first 12 chars 2016-05-20 20:57:18,123 Setting DEBUG logging. 2016-05-20 20:57:18,123 attempt 1/10 2016-05-20 20:57:18,123 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/894f88d5f106?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 20:57:18,377 unpacking tar archive at: larch-894f88d5f106/testing/mozharness/ program finished with exit code 0 elapsedTime=0.558766 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.681704) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.681972) ========= script_repo_revision: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.682314) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.682591) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 20:57:18.694541) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-20 20:57:18.694813) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:57:18 INFO - MultiFileLogger online at 20160520 20:57:18 in /builds/slave/test 20:57:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 20:57:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:57:18 INFO - {'append_to_log': False, 20:57:18 INFO - 'base_work_dir': '/builds/slave/test', 20:57:18 INFO - 'blob_upload_branch': 'Larch', 20:57:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:57:18 INFO - 'branch': 'Larch', 20:57:18 INFO - 'buildbot_json_path': 'buildprops.json', 20:57:18 INFO - 'config_files': ('talos/linux_config.py',), 20:57:18 INFO - 'default_actions': ('clobber', 20:57:18 INFO - 'read-buildbot-config', 20:57:18 INFO - 'download-and-extract', 20:57:18 INFO - 'populate-webroot', 20:57:18 INFO - 'create-virtualenv', 20:57:18 INFO - 'install', 20:57:18 INFO - 'run-tests'), 20:57:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:57:18 INFO - 'download_minidump_stackwalk': True, 20:57:18 INFO - 'download_symbols': 'ondemand', 20:57:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:57:18 INFO - 'tooltool.py': '/tools/tooltool.py', 20:57:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:57:18 INFO - '/tools/misc-python/virtualenv.py')}, 20:57:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:57:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:57:18 INFO - 'installer_path': 'installer.exe', 20:57:18 INFO - 'log_level': 'info', 20:57:18 INFO - 'log_name': 'talos', 20:57:18 INFO - 'log_to_console': True, 20:57:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:57:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:57:18 INFO - 'opt_config_files': (), 20:57:18 INFO - 'pip_index': False, 20:57:18 INFO - 'sps_profile': False, 20:57:18 INFO - 'sps_profile_interval': 0, 20:57:18 INFO - 'suite': 'dromaeojs-e10s', 20:57:18 INFO - 'system_bits': '32', 20:57:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:57:18 INFO - 'title': 'talos-linux64-ix-010', 20:57:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:57:18 INFO - 'use_talos_json': True, 20:57:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:57:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:57:18 INFO - 'work_dir': 'build'} 20:57:18 INFO - ##### 20:57:18 INFO - ##### Running clobber step. 20:57:18 INFO - ##### 20:57:18 INFO - Running pre-action listener: _resource_record_pre_action 20:57:18 INFO - Running main action method: clobber 20:57:18 INFO - rmtree: /builds/slave/test/build 20:57:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:57:19 INFO - Running post-action listener: _resource_record_post_action 20:57:19 INFO - ##### 20:57:19 INFO - ##### Finished clobber step (success) 20:57:19 INFO - ##### 20:57:19 INFO - ##### 20:57:19 INFO - ##### Running read-buildbot-config step. 20:57:19 INFO - ##### 20:57:19 INFO - Running pre-action listener: _resource_record_pre_action 20:57:19 INFO - Running main action method: read_buildbot_config 20:57:19 INFO - Using buildbot properties: 20:57:19 INFO - { 20:57:19 INFO - "project": "", 20:57:19 INFO - "product": "firefox", 20:57:19 INFO - "script_repo_revision": "production", 20:57:19 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 20:57:19 INFO - "repository": "", 20:57:19 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs-e10s", 20:57:19 INFO - "buildid": "20160520183008", 20:57:19 INFO - "pgo_build": "True", 20:57:19 INFO - "basedir": "/builds/slave/test", 20:57:19 INFO - "buildnumber": 1, 20:57:19 INFO - "slavename": "talos-linux64-ix-010", 20:57:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:57:19 INFO - "platform": "ubuntu64_hw", 20:57:19 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 20:57:19 INFO - "branch": "larch", 20:57:19 INFO - "repo_path": "projects/larch", 20:57:19 INFO - "revision": "894f88d5f1062eb50ac67f4abd48f54d0ee8775b", 20:57:19 INFO - "stage_platform": "linux64-pgo", 20:57:19 INFO - "builduid": "785bde8eac2a4f799a9bdfa199129e9c", 20:57:19 INFO - "slavebuilddir": "test" 20:57:19 INFO - } 20:57:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:57:19 INFO - Running post-action listener: _resource_record_post_action 20:57:19 INFO - ##### 20:57:19 INFO - ##### Finished read-buildbot-config step (success) 20:57:19 INFO - ##### 20:57:19 INFO - ##### 20:57:19 INFO - ##### Running download-and-extract step. 20:57:19 INFO - ##### 20:57:19 INFO - Running pre-action listener: _resource_record_pre_action 20:57:19 INFO - Running main action method: download_and_extract 20:57:19 INFO - mkdir: /builds/slave/test/build/tests 20:57:19 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')]} 20:57:19 INFO - https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:57:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:57:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:57:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:30 INFO - Downloaded 1361 bytes. 20:57:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:57:30 INFO - Using the following test package requirements: 20:57:30 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:57:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:57:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:57:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'jsshell-linux-x86_64.zip'], 20:57:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:57:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:57:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:57:30 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:57:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:57:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:57:30 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:57:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:57:30 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')]} 20:57:30 INFO - https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:57:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:57:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:57:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:32 INFO - Downloaded 23902717 bytes. 20:57:32 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'] 20:57:32 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 20:57:33 INFO - Return code: 0 20:57:33 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 20:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:33 INFO - https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:57:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:57:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:57:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:36 INFO - Downloaded 23902717 bytes. 20:57:36 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'] 20:57:36 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 20:57:38 INFO - Return code: 0 20:57: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')]} 20:57:38 INFO - https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:57:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:57:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:57:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:39 INFO - Downloaded 10840160 bytes. 20:57:39 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'] 20:57:39 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 20:57:39 INFO - Return code: 0 20:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:39 INFO - https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:57:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:57:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:57:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fB_MBqm7TSqqCka5crdXXw/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 20:57:41 INFO - Downloaded 58397629 bytes. 20:57:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:57:41 INFO - mkdir: /builds/slave/test/properties 20:57:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:57:41 INFO - Writing to file /builds/slave/test/properties/build_url 20:57:41 INFO - Contents: 20:57:41 INFO - build_url:https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:57:41 INFO - Running post-action listener: _resource_record_post_action 20:57:41 INFO - Running post-action listener: set_extra_try_arguments 20:57:41 INFO - ##### 20:57:41 INFO - ##### Finished download-and-extract step (success) 20:57:41 INFO - ##### 20:57:41 INFO - ##### 20:57:41 INFO - ##### Running populate-webroot step. 20:57:41 INFO - ##### 20:57:41 INFO - Running pre-action listener: _resource_record_pre_action 20:57:41 INFO - Running main action method: populate_webroot 20:57:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:57:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:57:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:57:41 INFO - '--tppagecycles', 20:57:41 INFO - '7'], 20:57:41 INFO - 'tests': ['tsvgm']}}, 20:57:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:57:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:57:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:57:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:57:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['damp', 'tps']}, 20:57:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'tests': ['damp', 'tps']}, 20:57:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['dromaeo_dom']}, 20:57:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:57:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'other-e10s': {'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['a11yr', 20:57:41 INFO - 'ts_paint', 20:57:41 INFO - 'tpaint', 20:57:41 INFO - 'sessionrestore', 20:57:41 INFO - 'sessionrestore_no_auto_restore', 20:57:41 INFO - 'tabpaint']}, 20:57:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['tsvgx', 20:57:41 INFO - 'tsvgr_opacity', 20:57:41 INFO - 'tart', 20:57:41 INFO - 'tscrollx', 20:57:41 INFO - 'cart']}, 20:57:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:57:41 INFO - 'tsvgr_opacity', 20:57:41 INFO - 'tart', 20:57:41 INFO - 'tscrollx', 20:57:41 INFO - 'cart']}, 20:57:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'talos_options': ['--disable-e10s'], 20:57:41 INFO - 'tests': ['tp5o']}, 20:57:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'tests': ['tp5o']}, 20:57:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'talos_options': ['--disable-e10s', 20:57:41 INFO - '--xperf_path', 20:57:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:41 INFO - 'tests': ['tp5n']}, 20:57:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:57:41 INFO - 'talos_options': ['--xperf_path', 20:57:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:41 INFO - 'tests': ['tp5n']}}, 20:57:41 INFO - 'talos.zip': {'path': '', 20:57:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:57:41 INFO - Running post-action listener: _resource_record_post_action 20:57:41 INFO - ##### 20:57:41 INFO - ##### Finished populate-webroot step (success) 20:57:41 INFO - ##### 20:57:41 INFO - ##### 20:57:41 INFO - ##### Running create-virtualenv step. 20:57:41 INFO - ##### 20:57:41 INFO - Running pre-action listener: _resource_record_pre_action 20:57:41 INFO - Running main action method: create_virtualenv 20:57:41 INFO - Creating virtualenv /builds/slave/test/build/venv 20:57:41 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 20:57:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:57:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:57:41 INFO - Using real prefix '/usr' 20:57:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:57:42 INFO - Installing distribute.............................................................................................................................................................................................done. 20:57:44 INFO - Installing pip.................done. 20:57:44 INFO - Return code: 0 20:57:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:57:44 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')]} 20:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:44 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')]} 20:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:44 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:44 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 20:57:44 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 20:57:44 INFO - Using env: {'DISPLAY': ':0', 20:57:44 INFO - 'HOME': '/home/cltbld', 20:57:44 INFO - 'LANG': 'en_US.UTF-8', 20:57:44 INFO - 'LANGUAGE': 'en_US:en', 20:57:44 INFO - 'LOGNAME': 'cltbld', 20:57:44 INFO - 'MAIL': '/var/mail/cltbld', 20:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:44 INFO - 'MOZ_NO_REMOTE': '1', 20:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:44 INFO - 'NO_EM_RESTART': '1', 20:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:44 INFO - 'PWD': '/builds/slave/test', 20:57:44 INFO - 'SHELL': '/bin/bash', 20:57:44 INFO - 'SHLVL': '1', 20:57:44 INFO - 'TERM': 'linux', 20:57:44 INFO - 'TMOUT': '86400', 20:57:44 INFO - 'USER': 'cltbld', 20:57:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:44 INFO - '_': '/tools/buildbot/bin/python'} 20:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:44 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 20:57:44 INFO - Cleaning up... 20:57:44 INFO - Return code: 0 20:57:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:57:44 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')]} 20:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:44 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')]} 20:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:44 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:44 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>=0.7.1'] in /builds/slave/test/build 20:57:44 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>=0.7.1 20:57:44 INFO - Using env: {'DISPLAY': ':0', 20:57:44 INFO - 'HOME': '/home/cltbld', 20:57:44 INFO - 'LANG': 'en_US.UTF-8', 20:57:44 INFO - 'LANGUAGE': 'en_US:en', 20:57:44 INFO - 'LOGNAME': 'cltbld', 20:57:44 INFO - 'MAIL': '/var/mail/cltbld', 20:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:44 INFO - 'MOZ_NO_REMOTE': '1', 20:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:44 INFO - 'NO_EM_RESTART': '1', 20:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:44 INFO - 'PWD': '/builds/slave/test', 20:57:44 INFO - 'SHELL': '/bin/bash', 20:57:44 INFO - 'SHLVL': '1', 20:57:44 INFO - 'TERM': 'linux', 20:57:44 INFO - 'TMOUT': '86400', 20:57:44 INFO - 'USER': 'cltbld', 20:57:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:44 INFO - '_': '/tools/buildbot/bin/python'} 20:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:44 INFO - Downloading/unpacking psutil>=0.7.1 20:57:44 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 20:57:44 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 20:57:44 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 20:57:44 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 20:57:44 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 20:57:44 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 20:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:57:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:46 INFO - Installing collected packages: psutil 20:57:46 INFO - Running setup.py install for psutil 20:57:46 INFO - building 'psutil._psutil_linux' extension 20:57:46 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 20:57:46 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 20:57:47 INFO - building 'psutil._psutil_posix' extension 20:57:47 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 20:57:47 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 20:57:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:47 INFO - Successfully installed psutil 20:57:47 INFO - Cleaning up... 20:57:47 INFO - Return code: 0 20:57:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:57:47 INFO - Using env: {'DISPLAY': ':0', 20:57:47 INFO - 'HOME': '/home/cltbld', 20:57:47 INFO - 'LANG': 'en_US.UTF-8', 20:57:47 INFO - 'LANGUAGE': 'en_US:en', 20:57:47 INFO - 'LOGNAME': 'cltbld', 20:57:47 INFO - 'MAIL': '/var/mail/cltbld', 20:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:47 INFO - 'MOZ_NO_REMOTE': '1', 20:57:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:47 INFO - 'NO_EM_RESTART': '1', 20:57:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:47 INFO - 'PWD': '/builds/slave/test', 20:57:47 INFO - 'SHELL': '/bin/bash', 20:57:47 INFO - 'SHLVL': '1', 20:57:47 INFO - 'TERM': 'linux', 20:57:47 INFO - 'TMOUT': '86400', 20:57:47 INFO - 'USER': 'cltbld', 20:57:47 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:47 INFO - '_': '/tools/buildbot/bin/python'} 20:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:57:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:57:49 INFO - Installing collected packages: mozsystemmonitor 20:57:49 INFO - Running setup.py install for mozsystemmonitor 20:57:49 INFO - Successfully installed mozsystemmonitor 20:57:49 INFO - Cleaning up... 20:57:50 INFO - Return code: 0 20:57:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:57: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')]} 20:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:57:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:57:50 INFO - Using env: {'DISPLAY': ':0', 20:57:50 INFO - 'HOME': '/home/cltbld', 20:57:50 INFO - 'LANG': 'en_US.UTF-8', 20:57:50 INFO - 'LANGUAGE': 'en_US:en', 20:57:50 INFO - 'LOGNAME': 'cltbld', 20:57:50 INFO - 'MAIL': '/var/mail/cltbld', 20:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:50 INFO - 'MOZ_NO_REMOTE': '1', 20:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:50 INFO - 'NO_EM_RESTART': '1', 20:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:50 INFO - 'PWD': '/builds/slave/test', 20:57:50 INFO - 'SHELL': '/bin/bash', 20:57:50 INFO - 'SHLVL': '1', 20:57:50 INFO - 'TERM': 'linux', 20:57:50 INFO - 'TMOUT': '86400', 20:57:50 INFO - 'USER': 'cltbld', 20:57:50 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:50 INFO - '_': '/tools/buildbot/bin/python'} 20:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:50 INFO - Downloading/unpacking blobuploader==1.2.4 20:57: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 20:57: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 20:57: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 20:57: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 20:57: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 20:57: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 20:57:52 INFO - Downloading blobuploader-1.2.4.tar.gz 20:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:57:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:57:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:52 INFO - Downloading docopt-0.6.1.tar.gz 20:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:57:53 INFO - Installing collected packages: blobuploader, requests, docopt 20:57:53 INFO - Running setup.py install for blobuploader 20:57:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:57:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:57:53 INFO - Running setup.py install for requests 20:57:53 INFO - Running setup.py install for docopt 20:57:53 INFO - Successfully installed blobuploader requests docopt 20:57:53 INFO - Cleaning up... 20:57:53 INFO - Return code: 0 20:57:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57: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')]} 20:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:53 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:53 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 20:57:53 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 20:57:53 INFO - Using env: {'DISPLAY': ':0', 20:57:53 INFO - 'HOME': '/home/cltbld', 20:57:53 INFO - 'LANG': 'en_US.UTF-8', 20:57:53 INFO - 'LANGUAGE': 'en_US:en', 20:57:53 INFO - 'LOGNAME': 'cltbld', 20:57:53 INFO - 'MAIL': '/var/mail/cltbld', 20:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:53 INFO - 'MOZ_NO_REMOTE': '1', 20:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:53 INFO - 'NO_EM_RESTART': '1', 20:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:53 INFO - 'PWD': '/builds/slave/test', 20:57:53 INFO - 'SHELL': '/bin/bash', 20:57:53 INFO - 'SHLVL': '1', 20:57:53 INFO - 'TERM': 'linux', 20:57:53 INFO - 'TMOUT': '86400', 20:57:53 INFO - 'USER': 'cltbld', 20:57:53 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:53 INFO - '_': '/tools/buildbot/bin/python'} 20:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:53 INFO - Running setup.py (path:/tmp/pip-mOiFcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:53 INFO - Running setup.py (path:/tmp/pip-9_dZds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:53 INFO - Running setup.py (path:/tmp/pip-yV3DLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:54 INFO - Running setup.py (path:/tmp/pip-K2MQhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:54 INFO - Running setup.py (path:/tmp/pip-yXTzRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:54 INFO - Running setup.py (path:/tmp/pip-968dCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:54 INFO - Running setup.py (path:/tmp/pip-pvtLYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:54 INFO - Running setup.py (path:/tmp/pip-ty85Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:54 INFO - Running setup.py (path:/tmp/pip-QiNqxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:54 INFO - Running setup.py (path:/tmp/pip-_MhUfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:54 INFO - Running setup.py (path:/tmp/pip-phAWsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:54 INFO - Running setup.py (path:/tmp/pip-ynBqdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:54 INFO - Running setup.py (path:/tmp/pip-hcQqHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:54 INFO - Running setup.py (path:/tmp/pip-QHfAXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:54 INFO - Running setup.py (path:/tmp/pip-0aY9QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:54 INFO - Running setup.py (path:/tmp/pip-TRXYTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:54 INFO - Running setup.py (path:/tmp/pip-BgLoXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:57:55 INFO - Running setup.py install for manifestparser 20:57:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Running setup.py install for mozcrash 20:57:55 INFO - Running setup.py install for mozdebug 20:57:55 INFO - Running setup.py install for mozdevice 20:57:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Running setup.py install for mozfile 20:57:55 INFO - Running setup.py install for mozhttpd 20:57:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Running setup.py install for mozinfo 20:57:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Running setup.py install for mozInstall 20:57:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:57:55 INFO - Running setup.py install for mozleak 20:57:55 INFO - Running setup.py install for mozlog 20:57:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Running setup.py install for moznetwork 20:57:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Running setup.py install for mozprocess 20:57:56 INFO - Running setup.py install for mozprofile 20:57:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Running setup.py install for mozrunner 20:57:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Running setup.py install for mozscreenshot 20:57:56 INFO - Running setup.py install for moztest 20:57:56 INFO - Running setup.py install for mozversion 20:57:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:57:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:57:56 INFO - Cleaning up... 20:57:56 INFO - Return code: 0 20:57:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:56 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')]} 20:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:56 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')]} 20:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:56 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:57:56 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 20:57:56 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 20:57:56 INFO - Using env: {'DISPLAY': ':0', 20:57:56 INFO - 'HOME': '/home/cltbld', 20:57:56 INFO - 'LANG': 'en_US.UTF-8', 20:57:56 INFO - 'LANGUAGE': 'en_US:en', 20:57:56 INFO - 'LOGNAME': 'cltbld', 20:57:56 INFO - 'MAIL': '/var/mail/cltbld', 20:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:56 INFO - 'MOZ_NO_REMOTE': '1', 20:57:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:56 INFO - 'NO_EM_RESTART': '1', 20:57:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:56 INFO - 'PWD': '/builds/slave/test', 20:57:56 INFO - 'SHELL': '/bin/bash', 20:57:56 INFO - 'SHLVL': '1', 20:57:56 INFO - 'TERM': 'linux', 20:57:56 INFO - 'TMOUT': '86400', 20:57:56 INFO - 'USER': 'cltbld', 20:57:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:56 INFO - '_': '/tools/buildbot/bin/python'} 20:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:57 INFO - Running setup.py (path:/tmp/pip-jcD_o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:57 INFO - Running setup.py (path:/tmp/pip-neaAe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:57 INFO - Running setup.py (path:/tmp/pip-YWGW8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:57 INFO - Running setup.py (path:/tmp/pip-uskhbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:57 INFO - Running setup.py (path:/tmp/pip-X8uRgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:57 INFO - Running setup.py (path:/tmp/pip-xooCOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:57 INFO - Running setup.py (path:/tmp/pip-lxEIW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:57 INFO - Running setup.py (path:/tmp/pip-SWipln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:57 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:57 INFO - Running setup.py (path:/tmp/pip-tCX5ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:57 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:57 INFO - Running setup.py (path:/tmp/pip-ghJVfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:57 INFO - Running setup.py (path:/tmp/pip-w1Befl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:57 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:57 INFO - Running setup.py (path:/tmp/pip-p8m63x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:57 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:57 INFO - Running setup.py (path:/tmp/pip-chDpuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:57 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)) 20:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:57 INFO - Running setup.py (path:/tmp/pip-PvRmcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:58 INFO - Running setup.py (path:/tmp/pip-hOGa_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:58 INFO - Running setup.py (path:/tmp/pip-ScQckr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:58 INFO - Running setup.py (path:/tmp/pip-mzvDqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:57:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:57: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 20:57: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 20:57: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 20:57: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 20:57: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 20:57: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 20:58:00 INFO - Downloading blessings-1.6.tar.gz 20:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:58:00 INFO - Installing collected packages: blessings 20:58:00 INFO - Running setup.py install for blessings 20:58:00 INFO - Successfully installed blessings 20:58:00 INFO - Cleaning up... 20:58:00 INFO - Return code: 0 20:58:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:58:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:58:00 INFO - Reading from file tmpfile_stdout 20:58:00 INFO - Current package versions: 20:58:00 INFO - argparse == 1.2.1 20:58:00 INFO - blessings == 1.6 20:58:00 INFO - blobuploader == 1.2.4 20:58:00 INFO - docopt == 0.6.1 20:58:00 INFO - manifestparser == 1.1 20:58:00 INFO - mozInstall == 1.12 20:58:00 INFO - mozcrash == 0.17 20:58:00 INFO - mozdebug == 0.1 20:58:00 INFO - mozdevice == 0.48 20:58:00 INFO - mozfile == 1.2 20:58:00 INFO - mozhttpd == 0.7 20:58:00 INFO - mozinfo == 0.9 20:58:00 INFO - mozleak == 0.1 20:58:00 INFO - mozlog == 3.1 20:58:00 INFO - moznetwork == 0.27 20:58:00 INFO - mozprocess == 0.22 20:58:00 INFO - mozprofile == 0.28 20:58:00 INFO - mozrunner == 6.11 20:58:00 INFO - mozscreenshot == 0.1 20:58:00 INFO - mozsystemmonitor == 0.0 20:58:00 INFO - moztest == 0.7 20:58:00 INFO - mozversion == 1.4 20:58:00 INFO - psutil == 3.1.1 20:58:00 INFO - requests == 1.2.3 20:58:00 INFO - wsgiref == 0.1.2 20:58:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:00 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:58:00 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 20:58:00 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 20:58:00 INFO - Using env: {'DISPLAY': ':0', 20:58:00 INFO - 'HOME': '/home/cltbld', 20:58:00 INFO - 'LANG': 'en_US.UTF-8', 20:58:00 INFO - 'LANGUAGE': 'en_US:en', 20:58:00 INFO - 'LOGNAME': 'cltbld', 20:58:00 INFO - 'MAIL': '/var/mail/cltbld', 20:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:58:00 INFO - 'MOZ_NO_REMOTE': '1', 20:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:58:00 INFO - 'NO_EM_RESTART': '1', 20:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:58:00 INFO - 'PWD': '/builds/slave/test', 20:58:00 INFO - 'SHELL': '/bin/bash', 20:58:00 INFO - 'SHLVL': '1', 20:58:00 INFO - 'TERM': 'linux', 20:58:00 INFO - 'TMOUT': '86400', 20:58:00 INFO - 'USER': 'cltbld', 20:58:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:58:00 INFO - '_': '/tools/buildbot/bin/python'} 20:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 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)) 20:58:00 INFO - Cleaning up... 20:58:00 INFO - Return code: 0 20:58:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:00 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:58:00 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 20:58:00 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 20:58:00 INFO - Using env: {'DISPLAY': ':0', 20:58:00 INFO - 'HOME': '/home/cltbld', 20:58:00 INFO - 'LANG': 'en_US.UTF-8', 20:58:00 INFO - 'LANGUAGE': 'en_US:en', 20:58:00 INFO - 'LOGNAME': 'cltbld', 20:58:00 INFO - 'MAIL': '/var/mail/cltbld', 20:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:58:00 INFO - 'MOZ_NO_REMOTE': '1', 20:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:58:00 INFO - 'NO_EM_RESTART': '1', 20:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:58:00 INFO - 'PWD': '/builds/slave/test', 20:58:00 INFO - 'SHELL': '/bin/bash', 20:58:00 INFO - 'SHLVL': '1', 20:58:00 INFO - 'TERM': 'linux', 20:58:00 INFO - 'TMOUT': '86400', 20:58:00 INFO - 'USER': 'cltbld', 20:58:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:58:00 INFO - '_': '/tools/buildbot/bin/python'} 20:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:58:01 INFO - Downloading/unpacking jsonschema 20:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:58:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:58:02 INFO - Downloading/unpacking functools32 (from jsonschema) 20:58:02 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 20:58:02 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 20:58:02 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 20:58:02 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 20:58:02 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 20:58:02 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 20:58:03 INFO - Downloading functools32-3.2.3-2.tar.gz 20:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:58:03 INFO - warning: no files found matching '*.txt' 20:58:03 INFO - no previously-included directories found matching 'build' 20:58:03 INFO - no previously-included directories found matching 'dist' 20:58:03 INFO - no previously-included directories found matching '.git*' 20:58:03 INFO - Installing collected packages: jsonschema, functools32 20:58:03 INFO - Running setup.py install for functools32 20:58:03 INFO - warning: no files found matching '*.txt' 20:58:03 INFO - no previously-included directories found matching 'build' 20:58:03 INFO - no previously-included directories found matching 'dist' 20:58:03 INFO - no previously-included directories found matching '.git*' 20:58:03 INFO - Successfully installed jsonschema functools32 20:58:03 INFO - Cleaning up... 20:58:03 INFO - Return code: 0 20:58:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:58:03 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')]} 20:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:03 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')]} 20:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:58:03 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 0x7f3240b37f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', '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 20:58:03 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 20:58:03 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 20:58:03 INFO - Using env: {'DISPLAY': ':0', 20:58:03 INFO - 'HOME': '/home/cltbld', 20:58:03 INFO - 'LANG': 'en_US.UTF-8', 20:58:03 INFO - 'LANGUAGE': 'en_US:en', 20:58:03 INFO - 'LOGNAME': 'cltbld', 20:58:03 INFO - 'MAIL': '/var/mail/cltbld', 20:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:58:03 INFO - 'MOZ_NO_REMOTE': '1', 20:58:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:58:03 INFO - 'NO_EM_RESTART': '1', 20:58:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:58:03 INFO - 'PWD': '/builds/slave/test', 20:58:03 INFO - 'SHELL': '/bin/bash', 20:58:03 INFO - 'SHLVL': '1', 20:58:03 INFO - 'TERM': 'linux', 20:58:03 INFO - 'TMOUT': '86400', 20:58:03 INFO - 'USER': 'cltbld', 20:58:03 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:58:03 INFO - '_': '/tools/buildbot/bin/python'} 20:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:58:03 INFO - Downloading/unpacking flake8 20:58: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 20:58: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 20:58: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 20:58: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 20:58: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 20:58: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 20:58:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:58:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:58:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:05 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 20:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:58:06 INFO - no previously-included directories found matching 'docs/_build' 20:58:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:58:06 INFO - Running setup.py install for pep8 20:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:58:06 INFO - no previously-included directories found matching 'docs/_build' 20:58:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:58:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:58:06 INFO - Cleaning up... 20:58:06 INFO - Return code: 0 20:58:06 INFO - Running post-action listener: _resource_record_post_action 20:58:06 INFO - Running post-action listener: _start_resource_monitoring 20:58:06 INFO - Starting resource monitoring. 20:58:06 INFO - ##### 20:58:06 INFO - ##### Finished create-virtualenv step (success) 20:58:06 INFO - ##### 20:58:06 INFO - ##### 20:58:06 INFO - ##### Running install step. 20:58:06 INFO - ##### 20:58:06 INFO - Running pre-action listener: _resource_record_pre_action 20:58:06 INFO - Running main action method: install 20:58:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:58:06 INFO - Reading from file tmpfile_stdout 20:58:06 INFO - Detecting whether we're running mozinstall >=1.0... 20:58:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:58:06 INFO - Reading from file tmpfile_stdout 20:58:06 INFO - Output received: 20:58:06 INFO - Usage: mozinstall [options] installer 20:58:06 INFO - Options: 20:58:06 INFO - -h, --help show this help message and exit 20:58:06 INFO - -d DEST, --destination=DEST 20:58:06 INFO - Directory to install application into. [default: 20:58:06 INFO - "/builds/slave/test"] 20:58:06 INFO - --app=APP Application being installed. [default: firefox] 20:58:06 INFO - mkdir: /builds/slave/test/build/application 20:58:06 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'] 20:58:06 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 20:58:23 INFO - Reading from file tmpfile_stdout 20:58:23 INFO - Output received: 20:58:23 INFO - /builds/slave/test/build/application/firefox/firefox 20:58:23 INFO - Running post-action listener: _resource_record_post_action 20:58:23 INFO - ##### 20:58:23 INFO - ##### Finished install step (success) 20:58:23 INFO - ##### 20:58:23 INFO - ##### 20:58:23 INFO - ##### Running run-tests step. 20:58:23 INFO - ##### 20:58:23 INFO - Running pre-action listener: _resource_record_pre_action 20:58:23 INFO - Running main action method: run_tests 20:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:58:23 INFO - Python 2.7.3 20:58:23 INFO - Return code: 0 20:58:23 INFO - grabbing minidump binary from tooltool 20:58:23 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')]} 20:58:23 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 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:58:23 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 20:58:23 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 20:58:23 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 20:58:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:58:23 INFO - Return code: 0 20:58:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:58:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:58:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:58:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:58:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:58:24 INFO - Return code: 0 20:58:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:58:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:58:24 INFO - Using env: {'DISPLAY': ':0', 20:58:24 INFO - 'HOME': '/home/cltbld', 20:58:24 INFO - 'LANG': 'en_US.UTF-8', 20:58:24 INFO - 'LANGUAGE': 'en_US:en', 20:58:24 INFO - 'LOGNAME': 'cltbld', 20:58:24 INFO - 'MAIL': '/var/mail/cltbld', 20:58:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:58:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:58:24 INFO - 'MOZ_NO_REMOTE': '1', 20:58:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:58:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:58:24 INFO - 'NO_EM_RESTART': '1', 20:58:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:58:24 INFO - 'PWD': '/builds/slave/test', 20:58:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:58:24 INFO - 'SHELL': '/bin/bash', 20:58:24 INFO - 'SHLVL': '1', 20:58:24 INFO - 'TERM': 'linux', 20:58:24 INFO - 'TMOUT': '86400', 20:58:24 INFO - 'USER': 'cltbld', 20:58:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384', 20:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:58:24 INFO - '_': '/tools/buildbot/bin/python'} 20:58:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:58:24 INFO - mozversion application_buildid: 20160520183008 20:58:24 INFO - mozversion application_changeset: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b 20:58:24 INFO - mozversion application_display_name: Nightly 20:58:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:58:24 INFO - mozversion application_name: Firefox 20:58:24 INFO - mozversion application_remotingname: firefox 20:58:24 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 20:58:24 INFO - mozversion application_vendor: Mozilla 20:58:24 INFO - mozversion application_version: 49.0a1 20:58:24 INFO - mozversion platform_buildid: 20160520183008 20:58:24 INFO - mozversion platform_changeset: 894f88d5f1062eb50ac67f4abd48f54d0ee8775b 20:58:24 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 20:58:24 INFO - mozversion platform_version: 49.0a1 20:58:24 INFO - using testdate: 1463803104 20:58:24 INFO - actual date: 1463803104 20:58:24 INFO - starting webserver on 'localhost:51936' 20:58:24 INFO - SUITE-START | Running 2 tests 20:58:24 INFO - TEST-START | dromaeo_css 20:58:24 INFO - operating with platform_type : linux_ 20:58:24 INFO - Initialising browser for dromaeo_css test... 20:58:24 INFO - TEST-INFO | started process 5364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyfWrTS/profile http://localhost:51936/getInfo.html) 20:58:28 INFO - PROCESS | 5364 | 20:58:28 INFO - PROCESS | 5364 | (process:5436): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:58:29 INFO - PROCESS | 5364 | __metrics Screen width/height:1600/1200 20:58:29 INFO - PROCESS | 5364 | colorDepth:24 20:58:29 INFO - PROCESS | 5364 | Browser inner width/height: 1024/697 20:58:29 INFO - PROCESS | 5364 | __metrics 20:58:30 ERROR - PROCESS | 5364 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 20:58:31 INFO - TEST-INFO | 5364: exit 0 20:58:31 INFO - Browser initialized. 20:58:31 INFO - Running cycle 1/1 for dromaeo_css test... 20:58:31 INFO - TEST-INFO | started process 5487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyfWrTS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:58:33 INFO - PROCESS | 5487 | 20:58:33 INFO - PROCESS | 5487 | (process:5547): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 20:58:43 INFO - PROCESS | 5487 | RSS: Main: 163659776 20:58:43 INFO - PROCESS | 5487 | 20:59:32 INFO - PROCESS | 5487 | 1463803172812 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] 21:01:40 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51936/tests/dromaeo/cssquery-ext.html) 21:01:42 INFO - PROCESS | 5487 | RSS: Main: 165900288 21:01:42 INFO - PROCESS | 5487 | 21:04:44 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-ext.html (next: http://localhost:51936/tests/dromaeo/cssquery-jquery.html) 21:04:44 INFO - PROCESS | 5487 | RSS: Main: 166862848 21:04:44 INFO - PROCESS | 5487 | 21:07:46 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51936/tests/dromaeo/cssquery-mootools.html) 21:07:47 INFO - PROCESS | 5487 | RSS: Main: 167940096 21:07:47 INFO - PROCESS | 5487 | 21:10:49 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51936/tests/dromaeo/cssquery-prototype.html) 21:10:49 INFO - PROCESS | 5487 | RSS: Main: 165847040 21:10:49 INFO - PROCESS | 5487 | 21:13:52 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51936/tests/dromaeo/cssquery-yui.html) 21:13:52 INFO - PROCESS | 5487 | RSS: Main: 167088128 21:13:52 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | Cycle 1(1): loaded http://localhost:51936/tests/dromaeo/cssquery-yui.html (next: http://localhost:51936/tests/dromaeo/cssquery-dojo.html) 21:16:55 INFO - PROCESS | 5487 | RSS: Main: 168685568 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | __start_tp_report 21:16:55 INFO - PROCESS | 5487 | _x_x_mozilla_page_load 21:16:55 INFO - PROCESS | 5487 | _x_x_mozilla_page_load_details 21:16:55 INFO - PROCESS | 5487 | |i|pagename|runs| 21:16:55 INFO - PROCESS | 5487 | |0;dojo.html;3178.9046328610143;3198.920026999326;3230.628477725062;3232.886848960287;3239.6598357172497;5377.354717433908;5402.027635025694;5450.100733378993;5478.534324582415;5510.779568817243;5342.305500284967;5436.184572314155;5470.8085217017315;5528.087865502187;5536.4186760390085;4941.851744447672;4966.602671786258;5004.049230646166;5008.449070602231;5030.11972904742;4966.354373931384;5116.8976620467565;5122.078025955327;5125.2312153176945;5156.303898973628;4887.951120488785;5011.398632164127;5037.5718063964605;5047.520485553867;5070.467600901926;5013.047520971004;5019.096562618727;5033.748312584356;5038;5068.493150684939;4995.625328100407;4999.125153098194;5007.4491805901325;5019.121653710623;5042.571381432583;5305.3898801637915;5334.839954801362;5396.406395296514;5405.810796622139;5479.726013699299;5835.970820145914;5837.854053648693;5862.765489380419;5960.523158147381;5986.161937328778;6007.459328660441;6011.188489553898;6015.398460153993;6026.5178785697035;6034.366391528914;5544.16837474378;5551.500364967132;5575.581831362664;5588.385277619459;5597.74810133545;6048.606840555438;6104.572679912363;6106;6107.389261073857;6114.541409394314;5652.85867853307;5743.282089738783;5839.737211825393;5849.2103566018895;5855.765769369177;5824.039033559416;5863.179154918314;5893.852653683606;5917.7928772493615;5920.2895652521975;5889.941100589025;5891.116332550152;5907.350191478934;5910.379410161957;5915.585908986416;5643.463870932255;5843.152742852262;5862.677552734555;5895.322037965689;5914.290285165722;4347.934780978288;4446.377507149002;4449.844255451044;4490.887727806766;4506.098780243964;4004.4193591929006;4026.0941288210506;4061.634452899253;4066.1461093170765;4071.796410179479;5408.891822163535;5546.528545073714;5577.442255774471;5671.801486947937;5691.203231547586;5929.673867937305;5943.732532036069;5948.434898684704;5972.283326000821;6023.307319658208;5557.221988921554;5596.552275817925;5608.074667679787;5620.522255608319;5630.774769009194;4940.851774446773;4991.226359914219;5009.774560144802;5009.799608015712;5081.136206844845;6104.969475152685;6117.602355846856;6118.479929205978;6126.295476020226;6146.846328841814;5892.558058145742;5909.704514774158;5929.584929054925;5934.970325148347;5936.970315148569;5907.586468947133;6077.544184186292;6112.321110680273;6123.08153776937;6213.099100630293;5900.7049647518315;5912.852178695567;5913.674747888907;5916.201312822714;5959;5744.109663002241;5964.224650795369;5999.460048595647;6014.879702405839;6020.277566692025;5761;5788.016037273589;5805.41945805433;5826.621269617462;5831.387704291158;5791.739371728198;5818.418158184148;5826.854328641648;5829.300483941955;5836.416358364299;5538.6122971391615;5611.747471363717;5623.875224954943;5705.515031222392;5709.600327977002;5598.888022239614;5613.214150018919;5722;5799.8550036249435;5867.061270196748;5585.888282234414;5624.6625202487985;5630.662160270397;5648.971755141198;5679.74441150141 21:16:55 INFO - PROCESS | 5487 | |1;ext.html;580.8605934575703;638.9680665724853;645.141961191615;663.6847497438715;695.4089024329323;39399.80300098499;43672.78163609185;43840.12319753603;43941;44044.33933491;44463;44945;45127;45176.54823451764;45370.77314613431;8858.557072146383;8926.196642302191;8965.506897120657;8973.327000474957;8988.191062804346;8829.470231786081;8893.555322233895;8926.955365223197;8932.776680582972;8934.749135121088;8940.687075952343;8987.41581797181;9016.368854180177;9033.23217526511;9037.051109633492;8893.199612034916;8951.060138685441;8964.50695211763;8971.775705607379;8981.910180898172;8789.64841406343;8820.426672266281;8915.153060459246;8915.375923685344;8921.420107693044;41546.37680434796;41826.790866045776;41889.37165942513;41961.58038419607;41964;3390.1863552747163;3465.740069494773;3546.095745584885;3555.2000799820094;3909.0618251619408;33509.49735753966;33656.65373385062;33697.83151084254;33729.83135084309;33875;4055.5133383993816;4057.6550993165615;4066.8373265069363;4113.526944401402;4126.257273690734;318891;319806.9999999977;319828;321206;324534;58630.413695863346;59456;59463.70268148631;59533.10700339499;59648.403515965154;36869.81565092158;37789.05527361838;37818.81090594529;40007.59992400097;40059.799701001306;16995.49013529596;17000.744988825176;17128.828711712722;17154.142292885306;17271.7409238859;13914.721705566035;13923.791143132861;13956.581302560939;14009.229492378017;14111.15333079998;20074.197032118846;20296.59406811885;20356.69464958027;20370.185192592427;20381.286654967003;7154.320339567733;7265.709371625076;7304.726734860166;7400.222976587488;7444.292792184734;1060.920430967665;1143.108375467137;1171.0455978377595;1188.2454641302663;1255.121415009497;31630.209244769067;32119.839400802848;32450;32699.182520436705;32728;1185.5731936502852;1205.5358686905604;1251.355551890777;1286.3761075878192;1312.7899536074183;17238.224279907183;17366.39217627402;17435.912820435817;17535.210915508833;17598.912005440146;469359;472997;475896;476983;480953;58680.41319586749;58934.11598826021;59404.70297648484;59582.10626840601;59600.40399595949;1424.173979092124;1432.1550285331705;1437.295725094717;1457.0820383158755;1466.0501532961136;31432.37135257236;31533.369332613678;31960.68039319577;32226.516602250515;32407.675923240466;51253.48746512487;51265;51274.48725512697;51547.48452515427;51765.223521646454;308507;312563;312574.05990790215;313379;313551;28704.282392940342;28848.980571359803;28947.710522895344;29103;29148;49208.753956229986;49861;50036.74981625069;50575.49424505708;50924.23613645724;42482.36276455918;42612;42650.57349426466;43095.78452107719;44558;43103;43226.13547728965;43433.3484997719;43806.78096609624;44306.77846610746;37401.25197496089;42561;42804.143917122106;43055.13889722125;43604;36822.26355472822;37782.43326350162;37869.43195852119;38195.42706859344;38226.42660360041;28283.43433131367;28507.857460712563;28583.999560015298;28613.713862861936;28623.856880715462 21:16:55 INFO - PROCESS | 5487 | |2;jquery.html;380.72017067455414;394.11521135051805;398.50983290552637;403.34657854276077;409.5310869054932;17657.381991630296;18573.349932752357;19199.90400047998;19361.128749206284;19415.223391064377;18794.34219802307;18842.34051808187;19291.710624340645;19777.110030048647;19827.306044288453;5510.641808282448;5511.862203444906;5687.857803554903;5696.060150075254;5713.228714123582;5623.4938855503;5629.774809007635;5653.434656534334;5667.886642267152;5690.430956904318;5426.267453893733;5616.859578510529;5624.128260119688;5644.153376993463;5655.886882262353;5578.191162281447;5578.497935185833;5622.3253209614695;5637.661740295595;5646.068398714207;4839.153148199086;4972.776225069881;5021.046001259749;5023.397192336906;5041.899162016776;17346.56633584158;17360.95834249949;18237.817621823877;21896.781032189632;22189.667154992687;2204.9669254961186;2328.0804082387363;2347.2371479269304;2715.022591866926;3124.562561241428;10143.391396516232;10331.483425828754;10528.947355263252;10650;10710;1957.4910523264055;1978.2779285560666;1999.7000449932482;2024.372444542182;2059.423361458794;81684.59157704233;86358.13641863626;88088.5595572018;88360.1254168117;92473.53763231209;10133.949330253301;10269.691909242734;10601.50864546831;10752.301100428447;10776.892231077745;14730.705385892437;14838.925805370904;14846.70306593862;14849.257537123101;14864.851351486346;8026.478278911852;8369.288610468057;8373.37199710012;8380.287675547524;8560.14398560139;8333.374996875258;8401.831963360699;8464;8570.957145214234;8589.14108589136;4446.5331140230455;4472.619827314649;4482.775861206992;4513.616342610849;4661.160991021648;1903.390914907244;1911.235505797692;1935.7386752788475;1949.7465329507074;1956.0708663384778;1066.7759770448138;1073.7906108308962;1085.913126949842;1123.6179698902413;1175.7119505720982;18767.718484222747;19041.61916761657;19051.61896762057;19560;19656.21375145007;1008.8688470498788;1035.052926572178;1045.7019749371393;1048.0776916313596;1048.4024106259512;10394.740131496774;10743.731406714738;10989.285696429706;11002.944985275022;11074.224804263786;451170;455593;463874;465924;467783;91924;95399;96011.51994239984;97019.51490242645;97528;1017.8574999500075;1038.2628333883029;1044.493420690949;1049.1082579807255;1059.5285098131258;16652.250648720594;16842.410515631895;16953.23710433009;17006.404775832787;17204.31182752676;38496.80751596336;40101.39847902225;40572.3914141294;41341.58658413377;41484;57569;59036.40963590481;59269.70365148147;59663.40336596579;59906.70046649739;6589.7364105435245;8318.292945099734;8441.11368306319;8444.873326899979;8555.058943516333;13189.208647481182;13508;13556.86443135556;13560.86439135596;13569.72860542764;11722.70693232676;11859.16985810985;11917.40412979365;12022.879771202175;12081.879181208427;11800.350980696145;11850.940745296219;11861.644150675493;11904.821427678418;11920.52317907274;12085.69785755328;12185.329806860707;12305.26168429897;12324.630261092181;12420.62738117858;8171.142030086993;8227.753167404988;8324.625391857502;8405.369597280107;8498.150184981452;11261.66215013551;11914.463849126983;12127.454264558293;12515.37423128858;12605.117641764988 21:16:55 INFO - PROCESS | 5487 | |3;mootools.html;1441.7621092519737;1468.6915747692983;1580.9051456912587;1732.4542769027755;1745.624690691501;7772.0673519177635;8495.447795893271;8626;9104.817903641931;9107.817843643132;8633.95683021584;8809.691660791874;8860.20258176764;8968.327375446836;8974.685885993991;2278.7721227877205;2291.175176936302;2296.402935236842;2300.0799680127916;2315.340128063502;2286.3598192506042;2313.8148948084113;2326.1393284484766;2331.35887630902;2336.6144586143264;2275.5903937291364;2278.4190031542016;2302.942426439344;2357.010055776578;2358.445765245166;2244.7418546867075;2255.977440225593;2288.6910267113976;2291.644795056769;2298.230092918866;2330.1378489958656;2333.2766842278947;2349.436135327509;2357.823163262745;2358.716953965518;8875.467471951704;8915.777105572413;8947.418417802886;8948.149925757043;8957.14907083826;5178.533931946143;5291.841244762663;5323.201519772027;5327.147656374961;5363.865903352408;3239.3359361331104;3239.8866039688496;3391.96608033919;3406.4890266459984;3412.078738740526;1026.431608523763;1036.3574583758043;1042.166266986408;1046.6493724602267;1050.716306597222;12428.502859885593;12482;12491.25052496844;12547.435365408579;12558;4565.771711414416;4595.701279416894;4694.741789201558;4721.126591580569;4733.526647335308;10536.894631053745;10578.471076446302;10589.84115238272;10604.204684648683;10643.946780266204;5354.143337066051;5380.139177731544;5399.973000134974;5400.594955378362;5400.62195646301;4341.848035318749;4676.836310729108;4813.975930120397;4826.927596086061;4873.122837889214;3945.585713500099;3974.4435778990965;4010.93710166806;4042.6967977401814;4075;1221.1390969366576;1222.1322860768953;1230.5139469909336;1236.0544183699476;1255.8241846141548;880.8326417980563;961.3318743473219;973.5959576775559;976.472704739447;1010.0455069958819;5610.242617246701;5627.352854421712;5670.291213598301;5803.738831752581;5815.912761308584;426.013778103689;447.85220877110476;454.41607534318905;463.2495357520838;464.5192226046026;3902.70729695274;3923.509561304837;3929.390944403622;3937.114149316381;3943.191645712622;10798.136149108053;11122.22144449897;11208.383538905438;11241.887581124247;11262.155338349656;11122.55509779616;11151;11192.272502287487;11283.153763467773;11303.208775385809;8216.46592971455;8376.664933402595;8404.495730256203;8447.324214062983;8494;5558.194061861089;5585.385607583082;5603.999303603753;5635;5671;9924.454155021544;10106.8483972739;10169.84745228837;10214.795704086026;10311.175105991653;10361.4301213434;10439.739006524898;10603;10745.838812417664;10785.62250321235;6992.370686638227;7765.883511747215;7800.219978002155;8014.599270036359;8200.26197642215;8910.821783564423;8914.509701966454;8950.23922966555;8979.191872731486;8990.280777537911;7784.299413052852;7789.065312162576;7974.40191985615;8044.637991290523;8082.636281367235;7866.252705992922;7957.403194760301;8013.919860801318;8025.478343907627;8078.596070196584;7288.489805713549;7319.304666056716;7342.743003994835;7384.96307518459;7450.627468626655;5024.271480635214;5057.974710126426;5121.871953201051;5176.482351764944;5186.714730689849;5047.293378927026;5155.43290238066;5176.4305926348825;5200.947990520198;5316.308879845596 21:16:55 INFO - PROCESS | 5487 | |4;prototype.html;344.34746156034316;345.55901889122356;346.3765222599321;352.30771533935825;353.6587193358409;5622.634528755628;5689.431056894308;5729.541636669067;5732.31212254529;5758.107493338534;5567.248421463101;5579.330480342354;5601.067631667069;5614.747336369863;5635.943640563593;4119.279126152926;4173.332266837307;4266.552012038738;4266.957330426687;4307.784610769465;4239.904869445291;4251.723637963538;4261.296886013804;4314.611684948354;4319.892002699926;4315.892102697427;4322.314297424007;4338;4346.913061738764;4353.586409291128;4189.308764053927;4235.08945576701;4280.957190428087;4296.140771845643;4305.9569404306185;3756.286305601927;3761.341765191108;3781.2059467511854;3785.0915780212827;3794.8861534153743;5328.866778330573;5353.892922141575;5373.7581808818695;5381.730913454312;5396.811111611075;4821.156297647897;4835.879103022453;4893.266010098479;4934;4963.2058870580895;5796.449337312949;5829.708514574297;5832.795852145155;5840.88318233633;5895.911561326584;4160.396742472324;4177.248095342837;4180.979095104535;4184.351425529047;4209.768462734548;5951.256092988377;6083.11794789753;6099.512039036911;6118.357572454917;6182.196314479089;4239.40648309237;4275.722078064916;4279.422277992493;4311.784410779448;4314.245007123741;4357.847475338348;4364.468608207232;4368.257396242647;4370.300751879684;4376.715513491613;4869.123557759637;4925.1873440881855;4960.330355401975;4967.826126085642;4975.577075948512;5844.415558444121;5880.235569376039;5885.911711324334;5928;5950.672713000827;1491.5972687374565;1500.197394393994;1514.174774747765;1521.0949485056594;1531.089002043782;938.6620816506189;945.8885809174193;959.5921733263335;966.9218821014658;970.1947383671536;5007.273945277987;5086.593072554261;5103.234514822806;5118.411382690963;5134.2812006319145;5360.65155764874;5366;5375.366070625443;5393.919091213635;5430.83707488776;4799.328094066834;4887.340209071801;4959.752012399365;5129.358830146231;5158.432572417031;2865.498537755884;2876.8561571921737;2897.1598236511177;2904.5062339402352;2907.941841163165;5404.486913585201;5479.60270131114;5507.146392309199;5562.443755624405;5587.156238441305;5264.499872512105;5430.70131142791;5464.672119672832;5473.370562385297;5554.250176226238;5503.624093976506;5559.193916882029;5587.245721827582;5589.524890384281;5608.205374280273;5125.5899528036925;5275.973620131875;5308.248278068306;5363.390982705095;5464.508194262536;5291.7618707159045;5300.178472336794;5354.179578943178;5367.812126575551;5385.499425373382;5504.22940788282;5504.697241651587;5568.916466252928;5573.554115670656;5599.880023995136;3733.5706393765063;3883.30100581898;3898.317794386028;3900.9609903901733;3904.9804750976064;5301.062809653213;5397.9190312144565;5445.346558413015;5844.824655260349;5970.253718285215;5884.47039766423;5888.528917686661;5914.467697907209;5925.229720136354;5949.244972732968;5846.152307915413;5899.46904778572;5923.200367950271;5925.903707814068;5934.762609495572;5534.861628459321;5771.384012476513;5780.450857168562;5783.566232532661;5808.5643576732755;5863.413658634103;5885.264341957255;5927.199828023162;5954.558630961285;5974.6713930732485;5617.859553511195;5794.565407594363;5797.304323481209;5809.2738407699035;5818.709064546841 21:16:55 INFO - PROCESS | 5487 | |5;yui.html;847.525385783961;852.5225873510834;856.8029353248753;867.0202670981789;869.1742844297914;13476.326183690813;14188.858111418882;14285.285735713225;14416.855831441682;14450.927745361258;12846.807297890538;12854.871451285484;12896.290704011275;12899.484020639187;12998.025148113882;671.4192223726491;803.1647087029482;809.2271880354277;810.096742132521;810.6635746165334;788.3417346515638;803.7468197517763;806.4919100966413;809.2029351089202;811.4806523824757;849.4563479373206;850.8553545897213;860.9440386374897;861.4874149880842;866.8223014282057;852.5694524265238;854.2141230068352;856.6830272799073;858.6222062292571;861.1560670542904;629.3077614623922;632.4118569730149;632.9873402531923;633.6103296472683;634.4511997122502;12944.093913426064;12956.157849739831;12984.415701293465;12990.415431305613;13012.869871301355;13643;13977.440902363893;14018.509352172625;14099.647508812259;14172.220527870963;11123.443827808658;11228.214025018253;11347;11382.71543211418;11410;3867.091233560111;3878.747881387729;3934.2721596504452;4027.7180597358197;4042.2319759245647;51741.482585174046;52109.47890521084;53093.46906530924;53176.73411632956;54225;11051.72370690723;11069.114470842313;11211.215214934877;11223.102151827834;11305.32168069902;19148.712769308473;19305.903470482557;19405.32081377145;19717.309894153637;20516.692249616266;8158.714444994397;8166.469179503313;8205.138460461685;8236.135205803424;8303.169683031649;4644.465886423037;4772.856814295576;4802.7838747255755;4833.758312084382;4905.803767849317;3279.7540184485783;3360.6135294440965;3379;3380.8985730428126;3388.4747864081105;595.0455986029299;599.8860216558927;613.1722175063718;616.2512547255106;621.3258614403333;604.6130476494959;611.6696983628791;636.2619361540591;638.7285403703407;645.6932956845461;8255.901151674387;8862.42394244385;8898.644054237759;8948.060453652402;8985.910140898639;700.8878579427267;714.3677845107014;739.5969196787763;744.0810598910341;749.2806905370905;4218.936715949263;4253.21315556623;4313.180495705806;4375.16871794358;4404.141192467502;68571.65714171497;68598;70274;70373;70613.2938670617;60129;61642;62513;62815.371846280956;66225;56577.717111415004;57155.42844571501;57294.12999894972;57369.71315143481;57437.42562574404;8227.958860205661;8274.793130171553;8359.582020899052;8441.620127094173;8603.096674849301;35774;36073.278534429686;36135.63864361428;36156.45765313575;37147.44278835766;45961;51302;52356.73821630867;52401.7379913098;52747;11990.460429280529;12082.516699331929;12288.81566776517;12724;12862.871371286168;13902.16587004783;14029.298535073001;14060.859391405955;14113.017425134216;14347.282635868374;13120.278384688552;13251.204927704368;13340.199588024361;13372.465101396225;13872.722545549233;12944.805827912778;13314.400851961878;13502.392392342565;14193.219372934587;14236.786448203078;12442.875571244173;13475.797863032258;14032.719345613235;14346.928265358607;14387;10851.945740271249;11237.662870113909;11258.437078146224;11289.7742045157;11723.41382930867;10162.141779788744;10579.31234469757;10906;11571.363575003455;11903.642890713292 21:16:55 INFO - PROCESS | 5487 | __end_tp_report 21:16:55 INFO - PROCESS | 5487 | __start_cc_report 21:16:55 INFO - PROCESS | 5487 | _x_x_mozilla_cycle_collect,339 21:16:55 INFO - PROCESS | 5487 | __end_cc_report 21:16:55 INFO - PROCESS | 5487 | __startTimestamp1463804215394__endTimestamp 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | ------- Summary: start ------- 21:16:55 INFO - PROCESS | 5487 | Number of tests: 6 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#0] dojo.html Cycles:165 Average:5481.93 Median:5648.97 stddev:629.34 (11.1%) stddev-sans-first:604.78 21:16:55 INFO - PROCESS | 5487 | Values: 3178.9 3198.9 3230.6 3232.9 3239.7 5377.4 5402.0 5450.1 5478.5 5510.8 5342.3 5436.2 5470.8 5528.1 5536.4 4941.9 4966.6 5004.0 5008.4 5030.1 4966.4 5116.9 5122.1 5125.2 5156.3 4888.0 5011.4 5037.6 5047.5 5070.5 5013.0 5019.1 5033.7 5038.0 5068.5 4995.6 4999.1 5007.4 5019.1 5042.6 5305.4 5334.8 5396.4 5405.8 5479.7 5836.0 5837.9 5862.8 5960.5 5986.2 6007.5 6011.2 6015.4 6026.5 6034.4 5544.2 5551.5 5575.6 5588.4 5597.7 6048.6 6104.6 6106.0 6107.4 6114.5 5652.9 5743.3 5839.7 5849.2 5855.8 5824.0 5863.2 5893.9 5917.8 5920.3 5889.9 5891.1 5907.4 5910.4 5915.6 5643.5 5843.2 5862.7 5895.3 5914.3 4347.9 4446.4 4449.8 4490.9 4506.1 4004.4 4026.1 4061.6 4066.1 4071.8 5408.9 5546.5 5577.4 5671.8 5691.2 5929.7 5943.7 5948.4 5972.3 6023.3 5557.2 5596.6 5608.1 5620.5 5630.8 4940.9 4991.2 5009.8 5009.8 5081.1 6105.0 6117.6 6118.5 6126.3 6146.8 5892.6 5909.7 5929.6 5935.0 5937.0 5907.6 6077.5 6112.3 6123.1 6213.1 5900.7 5912.9 5913.7 5916.2 5959.0 5744.1 5964.2 5999.5 6014.9 6020.3 5761.0 5788.0 5805.4 5826.6 5831.4 5791.7 5818.4 5826.9 5829.3 5836.4 5538.6 5611.7 5623.9 5705.5 5709.6 5598.9 5613.2 5722.0 5799.9 5867.1 5585.9 5624.7 5630.7 5649.0 5679.7 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#1] ext.html Cycles:180 Average:54204.72 Median:4119.89 stddev:99334.03 (2411.1%) stddev-sans-first:99531.10 21:16:55 INFO - PROCESS | 5487 | Values: 580.9 639.0 645.1 663.7 695.4 39399.8 43672.8 43840.1 43941.0 44044.3 44463.0 44945.0 45127.0 45176.5 45370.8 8858.6 8926.2 8965.5 8973.3 8988.2 8829.5 8893.6 8927.0 8932.8 8934.7 8940.7 8987.4 9016.4 9033.2 9037.1 8893.2 8951.1 8964.5 8971.8 8981.9 8789.6 8820.4 8915.2 8915.4 8921.4 41546.4 41826.8 41889.4 41961.6 41964.0 3390.2 3465.7 3546.1 3555.2 3909.1 33509.5 33656.7 33697.8 33729.8 33875.0 4055.5 4057.7 4066.8 4113.5 4126.3 318891.0 319807.0 319828.0 321206.0 324534.0 58630.4 59456.0 59463.7 59533.1 59648.4 36869.8 37789.1 37818.8 40007.6 40059.8 16995.5 17000.7 17128.8 17154.1 17271.7 13914.7 13923.8 13956.6 14009.2 14111.2 20074.2 20296.6 20356.7 20370.2 20381.3 7154.3 7265.7 7304.7 7400.2 7444.3 1060.9 1143.1 1171.0 1188.2 1255.1 31630.2 32119.8 32450.0 32699.2 32728.0 1185.6 1205.5 1251.4 1286.4 1312.8 17238.2 17366.4 17435.9 17535.2 17598.9 469359.0 472997.0 475896.0 476983.0 480953.0 58680.4 58934.1 59404.7 59582.1 59600.4 1424.2 1432.2 1437.3 1457.1 1466.1 31432.4 31533.4 31960.7 32226.5 32407.7 51253.5 51265.0 51274.5 51547.5 51765.2 308507.0 312563.0 312574.1 313379.0 313551.0 28704.3 28849.0 28947.7 29103.0 29148.0 49208.8 49861.0 50036.7 50575.5 50924.2 42482.4 42612.0 42650.6 43095.8 44558.0 43103.0 43226.1 43433.3 43806.8 44306.8 37401.3 42561.0 42804.1 43055.1 43604.0 36822.3 37782.4 37869.4 38195.4 38226.4 28283.4 28507.9 28584.0 28613.7 28623.9 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#2] jquery.html Cycles:180 Average:28348.24 Median:2012.04 stddev:76402.15 (3797.3%) stddev-sans-first:76587.62 21:16:55 INFO - PROCESS | 5487 | Values: 380.7 394.1 398.5 403.3 409.5 17657.4 18573.3 19199.9 19361.1 19415.2 18794.3 18842.3 19291.7 19777.1 19827.3 5510.6 5511.9 5687.9 5696.1 5713.2 5623.5 5629.8 5653.4 5667.9 5690.4 5426.3 5616.9 5624.1 5644.2 5655.9 5578.2 5578.5 5622.3 5637.7 5646.1 4839.2 4972.8 5021.0 5023.4 5041.9 17346.6 17361.0 18237.8 21896.8 22189.7 2205.0 2328.1 2347.2 2715.0 3124.6 10143.4 10331.5 10528.9 10650.0 10710.0 1957.5 1978.3 1999.7 2024.4 2059.4 81684.6 86358.1 88088.6 88360.1 92473.5 10133.9 10269.7 10601.5 10752.3 10776.9 14730.7 14838.9 14846.7 14849.3 14864.9 8026.5 8369.3 8373.4 8380.3 8560.1 8333.4 8401.8 8464.0 8571.0 8589.1 4446.5 4472.6 4482.8 4513.6 4661.2 1903.4 1911.2 1935.7 1949.7 1956.1 1066.8 1073.8 1085.9 1123.6 1175.7 18767.7 19041.6 19051.6 19560.0 19656.2 1008.9 1035.1 1045.7 1048.1 1048.4 10394.7 10743.7 10989.3 11002.9 11074.2 451170.0 455593.0 463874.0 465924.0 467783.0 91924.0 95399.0 96011.5 97019.5 97528.0 1017.9 1038.3 1044.5 1049.1 1059.5 16652.3 16842.4 16953.2 17006.4 17204.3 38496.8 40101.4 40572.4 41341.6 41484.0 57569.0 59036.4 59269.7 59663.4 59906.7 6589.7 8318.3 8441.1 8444.9 8555.1 13189.2 13508.0 13556.9 13560.9 13569.7 11722.7 11859.2 11917.4 12022.9 12081.9 11800.4 11850.9 11861.6 11904.8 11920.5 12085.7 12185.3 12305.3 12324.6 12420.6 8171.1 8227.8 8324.6 8405.4 8498.2 11261.7 11914.5 12127.5 12515.4 12605.1 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#3] mootools.html Cycles:180 Average:5852.78 Median:2529.48 stddev:3416.25 (135.1%) stddev-sans-first:3409.75 21:16:55 INFO - PROCESS | 5487 | Values: 1441.8 1468.7 1580.9 1732.5 1745.6 7772.1 8495.4 8626.0 9104.8 9107.8 8634.0 8809.7 8860.2 8968.3 8974.7 2278.8 2291.2 2296.4 2300.1 2315.3 2286.4 2313.8 2326.1 2331.4 2336.6 2275.6 2278.4 2302.9 2357.0 2358.4 2244.7 2256.0 2288.7 2291.6 2298.2 2330.1 2333.3 2349.4 2357.8 2358.7 8875.5 8915.8 8947.4 8948.1 8957.1 5178.5 5291.8 5323.2 5327.1 5363.9 3239.3 3239.9 3392.0 3406.5 3412.1 1026.4 1036.4 1042.2 1046.6 1050.7 12428.5 12482.0 12491.3 12547.4 12558.0 4565.8 4595.7 4694.7 4721.1 4733.5 10536.9 10578.5 10589.8 10604.2 10643.9 5354.1 5380.1 5400.0 5400.6 5400.6 4341.8 4676.8 4814.0 4826.9 4873.1 3945.6 3974.4 4010.9 4042.7 4075.0 1221.1 1222.1 1230.5 1236.1 1255.8 880.8 961.3 973.6 976.5 1010.0 5610.2 5627.4 5670.3 5803.7 5815.9 426.0 447.9 454.4 463.2 464.5 3902.7 3923.5 3929.4 3937.1 3943.2 10798.1 11122.2 11208.4 11241.9 11262.2 11122.6 11151.0 11192.3 11283.2 11303.2 8216.5 8376.7 8404.5 8447.3 8494.0 5558.2 5585.4 5604.0 5635.0 5671.0 9924.5 10106.8 10169.8 10214.8 10311.2 10361.4 10439.7 10603.0 10745.8 10785.6 6992.4 7765.9 7800.2 8014.6 8200.3 8910.8 8914.5 8950.2 8979.2 8990.3 7784.3 7789.1 7974.4 8044.6 8082.6 7866.3 7957.4 8013.9 8025.5 8078.6 7288.5 7319.3 7342.7 7385.0 7450.6 5024.3 5058.0 5121.9 5176.5 5186.7 5047.3 5155.4 5176.4 5200.9 5316.3 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#4] prototype.html Cycles:180 Average:4739.38 Median:5362.02 stddev:1377.57 (25.7%) stddev-sans-first:1341.35 21:16:55 INFO - PROCESS | 5487 | Values: 344.3 345.6 346.4 352.3 353.7 5622.6 5689.4 5729.5 5732.3 5758.1 5567.2 5579.3 5601.1 5614.7 5635.9 4119.3 4173.3 4266.6 4267.0 4307.8 4239.9 4251.7 4261.3 4314.6 4319.9 4315.9 4322.3 4338.0 4346.9 4353.6 4189.3 4235.1 4281.0 4296.1 4306.0 3756.3 3761.3 3781.2 3785.1 3794.9 5328.9 5353.9 5373.8 5381.7 5396.8 4821.2 4835.9 4893.3 4934.0 4963.2 5796.4 5829.7 5832.8 5840.9 5895.9 4160.4 4177.2 4181.0 4184.4 4209.8 5951.3 6083.1 6099.5 6118.4 6182.2 4239.4 4275.7 4279.4 4311.8 4314.2 4357.8 4364.5 4368.3 4370.3 4376.7 4869.1 4925.2 4960.3 4967.8 4975.6 5844.4 5880.2 5885.9 5928.0 5950.7 1491.6 1500.2 1514.2 1521.1 1531.1 938.7 945.9 959.6 966.9 970.2 5007.3 5086.6 5103.2 5118.4 5134.3 5360.7 5366.0 5375.4 5393.9 5430.8 4799.3 4887.3 4959.8 5129.4 5158.4 2865.5 2876.9 2897.2 2904.5 2907.9 5404.5 5479.6 5507.1 5562.4 5587.2 5264.5 5430.7 5464.7 5473.4 5554.3 5503.6 5559.2 5587.2 5589.5 5608.2 5125.6 5276.0 5308.2 5363.4 5464.5 5291.8 5300.2 5354.2 5367.8 5385.5 5504.2 5504.7 5568.9 5573.6 5599.9 3733.6 3883.3 3898.3 3901.0 3905.0 5301.1 5397.9 5445.3 5844.8 5970.3 5884.5 5888.5 5914.5 5925.2 5949.2 5846.2 5899.5 5923.2 5925.9 5934.8 5534.9 5771.4 5780.5 5783.6 5808.6 5863.4 5885.3 5927.2 5954.6 5974.7 5617.9 5794.6 5797.3 5809.3 5818.7 21:16:55 INFO - PROCESS | 5487 | 21:16:55 INFO - PROCESS | 5487 | [#5] yui.html Cycles:180 Average:15661.34 Median:25302.73 stddev:18874.09 (74.6%) stddev-sans-first:18894.25 21:16:55 INFO - PROCESS | 5487 | Values: 847.5 852.5 856.8 867.0 869.2 13476.3 14188.9 14285.3 14416.9 14450.9 12846.8 12854.9 12896.3 12899.5 12998.0 671.4 803.2 809.2 810.1 810.7 788.3 803.7 806.5 809.2 811.5 849.5 850.9 860.9 861.5 866.8 852.6 854.2 856.7 858.6 861.2 629.3 632.4 633.0 633.6 634.5 12944.1 12956.2 12984.4 12990.4 13012.9 13643.0 13977.4 14018.5 14099.6 14172.2 11123.4 11228.2 11347.0 11382.7 11410.0 3867.1 3878.7 3934.3 4027.7 4042.2 51741.5 52109.5 53093.5 53176.7 54225.0 11051.7 11069.1 11211.2 11223.1 11305.3 19148.7 19305.9 19405.3 19717.3 20516.7 8158.7 8166.5 8205.1 8236.1 8303.2 4644.5 4772.9 4802.8 4833.8 4905.8 3279.8 3360.6 3379.0 3380.9 3388.5 595.0 599.9 613.2 616.3 621.3 604.6 611.7 636.3 638.7 645.7 8255.9 8862.4 8898.6 8948.1 8985.9 700.9 714.4 739.6 744.1 749.3 4218.9 4253.2 4313.2 4375.2 4404.1 68571.7 68598.0 70274.0 70373.0 70613.3 60129.0 61642.0 62513.0 62815.4 66225.0 56577.7 57155.4 57294.1 57369.7 57437.4 8228.0 8274.8 8359.6 8441.6 8603.1 35774.0 36073.3 36135.6 36156.5 37147.4 45961.0 51302.0 52356.7 52401.7 52747.0 11990.5 12082.5 12288.8 12724.0 12862.9 13902.2 14029.3 14060.9 14113.0 14347.3 13120.3 13251.2 13340.2 13372.5 13872.7 12944.8 13314.4 13502.4 14193.2 14236.8 12442.9 13475.8 14032.7 14346.9 14387.0 10851.9 11237.7 11258.4 11289.8 11723.4 10162.1 10579.3 10906.0 11571.4 11903.6 21:16:55 INFO - PROCESS | 5487 | -------- Summary: end -------- 21:16:55 INFO - PROCESS | 5487 | 21:16:56 INFO - TEST-INFO | 5487: exit 0 21:16:56 INFO - TEST-OK | dromaeo_css | took 1111598ms 21:16:56 INFO - TEST-START | kraken 21:16:56 INFO - operating with platform_type : linux_ 21:16:56 INFO - Initialising browser for kraken test... 21:16:56 INFO - TEST-INFO | started process 5720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5k8z1/profile http://localhost:51936/getInfo.html) 21:17:00 INFO - PROCESS | 5720 | 21:17:00 INFO - PROCESS | 5720 | (process:5787): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:17:01 INFO - PROCESS | 5720 | __metrics Screen width/height:1600/1200 21:17:01 INFO - PROCESS | 5720 | colorDepth:24 21:17:01 INFO - PROCESS | 5720 | Browser inner width/height: 1024/697 21:17:01 INFO - PROCESS | 5720 | __metrics 21:17:02 ERROR - PROCESS | 5720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 21:17:02 INFO - TEST-INFO | 5720: exit 0 21:17:02 INFO - Browser initialized. 21:17:02 INFO - Running cycle 1/1 for kraken test... 21:17:02 INFO - TEST-INFO | started process 5841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5k8z1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:17:04 INFO - PROCESS | 5841 | 21:17:04 INFO - PROCESS | 5841 | (process:5901): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:17:14 INFO - PROCESS | 5841 | RSS: Main: 163078144 21:17:14 INFO - PROCESS | 5841 | 21:17:17 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51936/tests/kraken/driver.html?testName=audio-beat-detection) 21:17:18 INFO - PROCESS | 5841 | RSS: Main: 168300544 21:17:18 INFO - PROCESS | 5841 | 21:17:20 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51936/tests/kraken/driver.html?testName=audio-dft) 21:17:21 INFO - PROCESS | 5841 | RSS: Main: 168132608 21:17:21 INFO - PROCESS | 5841 | 21:17:24 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51936/tests/kraken/driver.html?testName=audio-fft) 21:17:25 INFO - PROCESS | 5841 | RSS: Main: 162979840 21:17:25 INFO - PROCESS | 5841 | 21:17:27 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51936/tests/kraken/driver.html?testName=audio-oscillator) 21:17:27 INFO - PROCESS | 5841 | RSS: Main: 163135488 21:17:27 INFO - PROCESS | 5841 | 21:17:30 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51936/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:17:30 INFO - PROCESS | 5841 | RSS: Main: 163332096 21:17:30 INFO - PROCESS | 5841 | 21:17:33 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51936/tests/kraken/driver.html?testName=imaging-darkroom) 21:17:34 INFO - PROCESS | 5841 | RSS: Main: 164110336 21:17:34 INFO - PROCESS | 5841 | 21:17:38 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51936/tests/kraken/driver.html?testName=imaging-desaturate) 21:17:38 INFO - PROCESS | 5841 | RSS: Main: 162705408 21:17:38 INFO - PROCESS | 5841 | 21:17:41 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51936/tests/kraken/driver.html?testName=json-parse-financial) 21:17:42 INFO - PROCESS | 5841 | RSS: Main: 163000320 21:17:42 INFO - PROCESS | 5841 | 21:17:43 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51936/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:17:44 INFO - PROCESS | 5841 | RSS: Main: 163328000 21:17:44 INFO - PROCESS | 5841 | 21:17:46 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:17:46 INFO - PROCESS | 5841 | RSS: Main: 163704832 21:17:46 INFO - PROCESS | 5841 | 21:17:48 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:17:49 INFO - PROCESS | 5841 | RSS: Main: 163700736 21:17:49 INFO - PROCESS | 5841 | 21:17:52 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:17:53 INFO - PROCESS | 5841 | RSS: Main: 164552704 21:17:53 INFO - PROCESS | 5841 | 21:17:55 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:17:56 INFO - PROCESS | 5841 | RSS: Main: 164487168 21:17:56 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | Cycle 1(1): loaded http://localhost:51936/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51936/tests/kraken/driver.html?testName=ai-astar) 21:17:58 INFO - PROCESS | 5841 | RSS: Main: 165765120 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | __start_tp_report 21:17:58 INFO - PROCESS | 5841 | _x_x_mozilla_page_load 21:17:58 INFO - PROCESS | 5841 | _x_x_mozilla_page_load_details 21:17:58 INFO - PROCESS | 5841 | |i|pagename|runs| 21:17:58 INFO - PROCESS | 5841 | |0;ai-astar;109;111;103;111;112;111;105;110;111;111 21:17:58 INFO - PROCESS | 5841 | |1;audio-beat-detection;147;125;151;204;132;161;149;128;190;127 21:17:58 INFO - PROCESS | 5841 | |2;audio-dft;163;167;162;163;166;165;168;162;163;160 21:17:58 INFO - PROCESS | 5841 | |3;audio-fft;76;74;78;162;76;76;74;75;178;76 21:17:58 INFO - PROCESS | 5841 | |4;audio-oscillator;91;92;181;91;92;92;153;92;93;149 21:17:58 INFO - PROCESS | 5841 | |5;imaging-gaussian-blur;117;117;118;118;117;117;117;117;118;118 21:17:58 INFO - PROCESS | 5841 | |6;imaging-darkroom;146;146;147;146;146;146;146;146;146;146 21:17:58 INFO - PROCESS | 5841 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 21:17:58 INFO - PROCESS | 5841 | |8;json-parse-financial;60;57;58;62;57;60;60;114;55;59 21:17:58 INFO - PROCESS | 5841 | |9;json-stringify-tinderbox;59;58;58;59;58;58;58;59;58;60 21:17:58 INFO - PROCESS | 5841 | |10;stanford-crypto-aes;78;80;81;86;81;80;80;79;85;80 21:17:58 INFO - PROCESS | 5841 | |11;stanford-crypto-ccm;124;133;124;124;132;128;123;126;185;126 21:17:58 INFO - PROCESS | 5841 | |12;stanford-crypto-pbkdf2;170;169;172;167;178;170;169;169;173;168 21:17:58 INFO - PROCESS | 5841 | |13;stanford-crypto-sha256-iterative;66;65;64;66;69;67;64;68;64;64 21:17:58 INFO - PROCESS | 5841 | __end_tp_report 21:17:58 INFO - PROCESS | 5841 | __start_cc_report 21:17:58 INFO - PROCESS | 5841 | _x_x_mozilla_cycle_collect,822 21:17:58 INFO - PROCESS | 5841 | __end_cc_report 21:17:58 INFO - PROCESS | 5841 | __startTimestamp1463804278471__endTimestamp 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | ------- Summary: start ------- 21:17:58 INFO - PROCESS | 5841 | Number of tests: 14 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:2.99 (2.7%) stddev-sans-first:3.17 21:17:58 INFO - PROCESS | 5841 | Values: 109.0 111.0 103.0 111.0 112.0 111.0 105.0 110.0 111.0 111.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:150.00 stddev:27.06 (18.0%) stddev-sans-first:28.66 21:17:58 INFO - PROCESS | 5841 | Values: 147.0 125.0 151.0 204.0 132.0 161.0 149.0 128.0 190.0 127.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:2.51 (1.5%) stddev-sans-first:2.65 21:17:58 INFO - PROCESS | 5841 | Values: 163.0 167.0 162.0 163.0 166.0 165.0 168.0 162.0 163.0 160.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#3] audio-fft Cycles:10 Average:94.50 Median:76.00 stddev:39.99 (52.6%) stddev-sans-first:41.85 21:17:58 INFO - PROCESS | 5841 | Values: 76.0 74.0 78.0 162.0 76.0 76.0 74.0 75.0 178.0 76.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#4] audio-oscillator Cycles:10 Average:112.60 Median:92.00 stddev:34.40 (37.4%) stddev-sans-first:35.59 21:17:58 INFO - PROCESS | 5841 | Values: 91.0 92.0 181.0 91.0 92.0 92.0 153.0 92.0 93.0 149.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 21:17:58 INFO - PROCESS | 5841 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 118.0 118.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 21:17:58 INFO - PROCESS | 5841 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 21:17:58 INFO - PROCESS | 5841 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:59.50 stddev:17.61 (29.6%) stddev-sans-first:18.61 21:17:58 INFO - PROCESS | 5841 | Values: 60.0 57.0 58.0 62.0 57.0 60.0 60.0 114.0 55.0 59.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 21:17:58 INFO - PROCESS | 5841 | Values: 59.0 58.0 58.0 59.0 58.0 58.0 58.0 59.0 58.0 60.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:80.50 stddev:2.54 (3.2%) stddev-sans-first:2.45 21:17:58 INFO - PROCESS | 5841 | Values: 78.0 80.0 81.0 86.0 81.0 80.0 80.0 79.0 85.0 80.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:127.00 stddev:18.76 (14.8%) stddev-sans-first:19.65 21:17:58 INFO - PROCESS | 5841 | Values: 124.0 133.0 124.0 124.0 132.0 128.0 123.0 126.0 185.0 126.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:170.00 stddev:3.17 (1.9%) stddev-sans-first:3.36 21:17:58 INFO - PROCESS | 5841 | Values: 170.0 169.0 172.0 167.0 178.0 170.0 169.0 169.0 173.0 168.0 21:17:58 INFO - PROCESS | 5841 | 21:17:58 INFO - PROCESS | 5841 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.83 (2.8%) stddev-sans-first:1.94 21:17:58 INFO - PROCESS | 5841 | Values: 66.0 65.0 64.0 66.0 69.0 67.0 64.0 68.0 64.0 64.0 21:17:58 INFO - PROCESS | 5841 | -------- Summary: end -------- 21:17:58 INFO - PROCESS | 5841 | 21:17:59 INFO - TEST-INFO | 5841: exit 0 21:17:59 INFO - TEST-OK | kraken | took 63284ms 21:17:59 INFO - SUITE-END | took 1174s 21:17:59 INFO - Completed test suite (00:19:35) 21:17:59 INFO - javascript benchmark 21:17:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3178.9046328610143, 3198.920026999326, 3230.628477725062, 3232.886848960287, 3239.6598357172497, 5377.354717433908, 5402.027635025694, 5450.100733378993, 5478.534324582415, 5510.779568817243, 5342.305500284967, 5436.184572314155, 5470.8085217017315, 5528.087865502187, 5536.4186760390085, 4941.851744447672, 4966.602671786258, 5004.049230646166, 5008.449070602231, 5030.11972904742, 4966.354373931384, 5116.8976620467565, 5122.078025955327, 5125.2312153176945, 5156.303898973628, 4887.951120488785, 5011.398632164127, 5037.5718063964605, 5047.520485553867, 5070.467600901926, 5013.047520971004, 5019.096562618727, 5033.748312584356, 5038.0, 5068.493150684939, 4995.625328100407, 4999.125153098194, 5007.4491805901325, 5019.121653710623, 5042.571381432583, 5305.3898801637915, 5334.839954801362, 5396.406395296514, 5405.810796622139, 5479.726013699299, 5835.970820145914, 5837.854053648693, 5862.765489380419, 5960.523158147381, 5986.161937328778, 6007.459328660441, 6011.188489553898, 6015.398460153993, 6026.5178785697035, 6034.366391528914, 5544.16837474378, 5551.500364967132, 5575.581831362664, 5588.385277619459, 5597.74810133545, 6048.606840555438, 6104.572679912363, 6106.0, 6107.389261073857, 6114.541409394314, 5652.85867853307, 5743.282089738783, 5839.737211825393, 5849.2103566018895, 5855.765769369177, 5824.039033559416, 5863.179154918314, 5893.852653683606, 5917.7928772493615, 5920.2895652521975, 5889.941100589025, 5891.116332550152, 5907.350191478934, 5910.379410161957, 5915.585908986416, 5643.463870932255, 5843.152742852262, 5862.677552734555, 5895.322037965689, 5914.290285165722, 4347.934780978288, 4446.377507149002, 4449.844255451044, 4490.887727806766, 4506.098780243964, 4004.4193591929006, 4026.0941288210506, 4061.634452899253, 4066.1461093170765, 4071.796410179479, 5408.891822163535, 5546.528545073714, 5577.442255774471, 5671.801486947937, 5691.203231547586, 5929.673867937305, 5943.732532036069, 5948.434898684704, 5972.283326000821, 6023.307319658208, 5557.221988921554, 5596.552275817925, 5608.074667679787, 5620.522255608319, 5630.774769009194, 4940.851774446773, 4991.226359914219, 5009.774560144802, 5009.799608015712, 5081.136206844845, 6104.969475152685, 6117.602355846856, 6118.479929205978, 6126.295476020226, 6146.846328841814, 5892.558058145742, 5909.704514774158, 5929.584929054925, 5934.970325148347, 5936.970315148569, 5907.586468947133, 6077.544184186292, 6112.321110680273, 6123.08153776937, 6213.099100630293, 5900.7049647518315, 5912.852178695567, 5913.674747888907, 5916.201312822714, 5959.0, 5744.109663002241, 5964.224650795369, 5999.460048595647, 6014.879702405839, 6020.277566692025, 5761.0, 5788.016037273589, 5805.41945805433, 5826.621269617462, 5831.387704291158, 5791.739371728198, 5818.418158184148, 5826.854328641648, 5829.300483941955, 5836.416358364299, 5538.6122971391615, 5611.747471363717, 5623.875224954943, 5705.515031222392, 5709.600327977002, 5598.888022239614, 5613.214150018919, 5722.0, 5799.8550036249435, 5867.061270196748, 5585.888282234414, 5624.6625202487985, 5630.662160270397, 5648.971755141198, 5679.74441150141], "value": 5439.364406576566, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.8605934575703, 638.9680665724853, 645.141961191615, 663.6847497438715, 695.4089024329323, 39399.80300098499, 43672.78163609185, 43840.12319753603, 43941.0, 44044.33933491, 44463.0, 44945.0, 45127.0, 45176.54823451764, 45370.77314613431, 8858.557072146383, 8926.196642302191, 8965.506897120657, 8973.327000474957, 8988.191062804346, 8829.470231786081, 8893.555322233895, 8926.955365223197, 8932.776680582972, 8934.749135121088, 8940.687075952343, 8987.41581797181, 9016.368854180177, 9033.23217526511, 9037.051109633492, 8893.199612034916, 8951.060138685441, 8964.50695211763, 8971.775705607379, 8981.910180898172, 8789.64841406343, 8820.426672266281, 8915.153060459246, 8915.375923685344, 8921.420107693044, 41546.37680434796, 41826.790866045776, 41889.37165942513, 41961.58038419607, 41964.0, 3390.1863552747163, 3465.740069494773, 3546.095745584885, 3555.2000799820094, 3909.0618251619408, 33509.49735753966, 33656.65373385062, 33697.83151084254, 33729.83135084309, 33875.0, 4055.5133383993816, 4057.6550993165615, 4066.8373265069363, 4113.526944401402, 4126.257273690734, 318891.0, 319806.9999999977, 319828.0, 321206.0, 324534.0, 58630.413695863346, 59456.0, 59463.70268148631, 59533.10700339499, 59648.403515965154, 36869.81565092158, 37789.05527361838, 37818.81090594529, 40007.59992400097, 40059.799701001306, 16995.49013529596, 17000.744988825176, 17128.828711712722, 17154.142292885306, 17271.7409238859, 13914.721705566035, 13923.791143132861, 13956.581302560939, 14009.229492378017, 14111.15333079998, 20074.197032118846, 20296.59406811885, 20356.69464958027, 20370.185192592427, 20381.286654967003, 7154.320339567733, 7265.709371625076, 7304.726734860166, 7400.222976587488, 7444.292792184734, 1060.920430967665, 1143.108375467137, 1171.0455978377595, 1188.2454641302663, 1255.121415009497, 31630.209244769067, 32119.839400802848, 32450.0, 32699.182520436705, 32728.0, 1185.5731936502852, 1205.5358686905604, 1251.355551890777, 1286.3761075878192, 1312.7899536074183, 17238.224279907183, 17366.39217627402, 17435.912820435817, 17535.210915508833, 17598.912005440146, 469359.0, 472997.0, 475896.0, 476983.0, 480953.0, 58680.41319586749, 58934.11598826021, 59404.70297648484, 59582.10626840601, 59600.40399595949, 1424.173979092124, 1432.1550285331705, 1437.295725094717, 1457.0820383158755, 1466.0501532961136, 31432.37135257236, 31533.369332613678, 31960.68039319577, 32226.516602250515, 32407.675923240466, 51253.48746512487, 51265.0, 51274.48725512697, 51547.48452515427, 51765.223521646454, 308507.0, 312563.0, 312574.05990790215, 313379.0, 313551.0, 28704.282392940342, 28848.980571359803, 28947.710522895344, 29103.0, 29148.0, 49208.753956229986, 49861.0, 50036.74981625069, 50575.49424505708, 50924.23613645724, 42482.36276455918, 42612.0, 42650.57349426466, 43095.78452107719, 44558.0, 43103.0, 43226.13547728965, 43433.3484997719, 43806.78096609624, 44306.77846610746, 37401.25197496089, 42561.0, 42804.143917122106, 43055.13889722125, 43604.0, 36822.26355472822, 37782.43326350162, 37869.43195852119, 38195.42706859344, 38226.42660360041, 28283.43433131367, 28507.857460712563, 28583.999560015298, 28613.713862861936, 28623.856880715462], "value": 20084.831919294476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.72017067455414, 394.11521135051805, 398.50983290552637, 403.34657854276077, 409.5310869054932, 17657.381991630296, 18573.349932752357, 19199.90400047998, 19361.128749206284, 19415.223391064377, 18794.34219802307, 18842.34051808187, 19291.710624340645, 19777.110030048647, 19827.306044288453, 5510.641808282448, 5511.862203444906, 5687.857803554903, 5696.060150075254, 5713.228714123582, 5623.4938855503, 5629.774809007635, 5653.434656534334, 5667.886642267152, 5690.430956904318, 5426.267453893733, 5616.859578510529, 5624.128260119688, 5644.153376993463, 5655.886882262353, 5578.191162281447, 5578.497935185833, 5622.3253209614695, 5637.661740295595, 5646.068398714207, 4839.153148199086, 4972.776225069881, 5021.046001259749, 5023.397192336906, 5041.899162016776, 17346.56633584158, 17360.95834249949, 18237.817621823877, 21896.781032189632, 22189.667154992687, 2204.9669254961186, 2328.0804082387363, 2347.2371479269304, 2715.022591866926, 3124.562561241428, 10143.391396516232, 10331.483425828754, 10528.947355263252, 10650.0, 10710.0, 1957.4910523264055, 1978.2779285560666, 1999.7000449932482, 2024.372444542182, 2059.423361458794, 81684.59157704233, 86358.13641863626, 88088.5595572018, 88360.1254168117, 92473.53763231209, 10133.949330253301, 10269.691909242734, 10601.50864546831, 10752.301100428447, 10776.892231077745, 14730.705385892437, 14838.925805370904, 14846.70306593862, 14849.257537123101, 14864.851351486346, 8026.478278911852, 8369.288610468057, 8373.37199710012, 8380.287675547524, 8560.14398560139, 8333.374996875258, 8401.831963360699, 8464.0, 8570.957145214234, 8589.14108589136, 4446.5331140230455, 4472.619827314649, 4482.775861206992, 4513.616342610849, 4661.160991021648, 1903.390914907244, 1911.235505797692, 1935.7386752788475, 1949.7465329507074, 1956.0708663384778, 1066.7759770448138, 1073.7906108308962, 1085.913126949842, 1123.6179698902413, 1175.7119505720982, 18767.718484222747, 19041.61916761657, 19051.61896762057, 19560.0, 19656.21375145007, 1008.8688470498788, 1035.052926572178, 1045.7019749371393, 1048.0776916313596, 1048.4024106259512, 10394.740131496774, 10743.731406714738, 10989.285696429706, 11002.944985275022, 11074.224804263786, 451170.0, 455593.0, 463874.0, 465924.0, 467783.0, 91924.0, 95399.0, 96011.51994239984, 97019.51490242645, 97528.0, 1017.8574999500075, 1038.2628333883029, 1044.493420690949, 1049.1082579807255, 1059.5285098131258, 16652.250648720594, 16842.410515631895, 16953.23710433009, 17006.404775832787, 17204.31182752676, 38496.80751596336, 40101.39847902225, 40572.3914141294, 41341.58658413377, 41484.0, 57569.0, 59036.40963590481, 59269.70365148147, 59663.40336596579, 59906.70046649739, 6589.7364105435245, 8318.292945099734, 8441.11368306319, 8444.873326899979, 8555.058943516333, 13189.208647481182, 13508.0, 13556.86443135556, 13560.86439135596, 13569.72860542764, 11722.70693232676, 11859.16985810985, 11917.40412979365, 12022.879771202175, 12081.879181208427, 11800.350980696145, 11850.940745296219, 11861.644150675493, 11904.821427678418, 11920.52317907274, 12085.69785755328, 12185.329806860707, 12305.26168429897, 12324.630261092181, 12420.62738117858, 8171.142030086993, 8227.753167404988, 8324.625391857502, 8405.369597280107, 8498.150184981452, 11261.66215013551, 11914.463849126983, 12127.454264558293, 12515.37423128858, 12605.117641764988], "value": 9215.406121414515, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.7621092519737, 1468.6915747692983, 1580.9051456912587, 1732.4542769027755, 1745.624690691501, 7772.0673519177635, 8495.447795893271, 8626.0, 9104.817903641931, 9107.817843643132, 8633.95683021584, 8809.691660791874, 8860.20258176764, 8968.327375446836, 8974.685885993991, 2278.7721227877205, 2291.175176936302, 2296.402935236842, 2300.0799680127916, 2315.340128063502, 2286.3598192506042, 2313.8148948084113, 2326.1393284484766, 2331.35887630902, 2336.6144586143264, 2275.5903937291364, 2278.4190031542016, 2302.942426439344, 2357.010055776578, 2358.445765245166, 2244.7418546867075, 2255.977440225593, 2288.6910267113976, 2291.644795056769, 2298.230092918866, 2330.1378489958656, 2333.2766842278947, 2349.436135327509, 2357.823163262745, 2358.716953965518, 8875.467471951704, 8915.777105572413, 8947.418417802886, 8948.149925757043, 8957.14907083826, 5178.533931946143, 5291.841244762663, 5323.201519772027, 5327.147656374961, 5363.865903352408, 3239.3359361331104, 3239.8866039688496, 3391.96608033919, 3406.4890266459984, 3412.078738740526, 1026.431608523763, 1036.3574583758043, 1042.166266986408, 1046.6493724602267, 1050.716306597222, 12428.502859885593, 12482.0, 12491.25052496844, 12547.435365408579, 12558.0, 4565.771711414416, 4595.701279416894, 4694.741789201558, 4721.126591580569, 4733.526647335308, 10536.894631053745, 10578.471076446302, 10589.84115238272, 10604.204684648683, 10643.946780266204, 5354.143337066051, 5380.139177731544, 5399.973000134974, 5400.594955378362, 5400.62195646301, 4341.848035318749, 4676.836310729108, 4813.975930120397, 4826.927596086061, 4873.122837889214, 3945.585713500099, 3974.4435778990965, 4010.93710166806, 4042.6967977401814, 4075.0, 1221.1390969366576, 1222.1322860768953, 1230.5139469909336, 1236.0544183699476, 1255.8241846141548, 880.8326417980563, 961.3318743473219, 973.5959576775559, 976.472704739447, 1010.0455069958819, 5610.242617246701, 5627.352854421712, 5670.291213598301, 5803.738831752581, 5815.912761308584, 426.013778103689, 447.85220877110476, 454.41607534318905, 463.2495357520838, 464.5192226046026, 3902.70729695274, 3923.509561304837, 3929.390944403622, 3937.114149316381, 3943.191645712622, 10798.136149108053, 11122.22144449897, 11208.383538905438, 11241.887581124247, 11262.155338349656, 11122.55509779616, 11151.0, 11192.272502287487, 11283.153763467773, 11303.208775385809, 8216.46592971455, 8376.664933402595, 8404.495730256203, 8447.324214062983, 8494.0, 5558.194061861089, 5585.385607583082, 5603.999303603753, 5635.0, 5671.0, 9924.454155021544, 10106.8483972739, 10169.84745228837, 10214.795704086026, 10311.175105991653, 10361.4301213434, 10439.739006524898, 10603.0, 10745.838812417664, 10785.62250321235, 6992.370686638227, 7765.883511747215, 7800.219978002155, 8014.599270036359, 8200.26197642215, 8910.821783564423, 8914.509701966454, 8950.23922966555, 8979.191872731486, 8990.280777537911, 7784.299413052852, 7789.065312162576, 7974.40191985615, 8044.637991290523, 8082.636281367235, 7866.252705992922, 7957.403194760301, 8013.919860801318, 8025.478343907627, 8078.596070196584, 7288.489805713549, 7319.304666056716, 7342.743003994835, 7384.96307518459, 7450.627468626655, 5024.271480635214, 5057.974710126426, 5121.871953201051, 5176.482351764944, 5186.714730689849, 5047.293378927026, 5155.43290238066, 5176.4305926348825, 5200.947990520198, 5316.308879845596], "value": 4561.827732507773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.34746156034316, 345.55901889122356, 346.3765222599321, 352.30771533935825, 353.6587193358409, 5622.634528755628, 5689.431056894308, 5729.541636669067, 5732.31212254529, 5758.107493338534, 5567.248421463101, 5579.330480342354, 5601.067631667069, 5614.747336369863, 5635.943640563593, 4119.279126152926, 4173.332266837307, 4266.552012038738, 4266.957330426687, 4307.784610769465, 4239.904869445291, 4251.723637963538, 4261.296886013804, 4314.611684948354, 4319.892002699926, 4315.892102697427, 4322.314297424007, 4338.0, 4346.913061738764, 4353.586409291128, 4189.308764053927, 4235.08945576701, 4280.957190428087, 4296.140771845643, 4305.9569404306185, 3756.286305601927, 3761.341765191108, 3781.2059467511854, 3785.0915780212827, 3794.8861534153743, 5328.866778330573, 5353.892922141575, 5373.7581808818695, 5381.730913454312, 5396.811111611075, 4821.156297647897, 4835.879103022453, 4893.266010098479, 4934.0, 4963.2058870580895, 5796.449337312949, 5829.708514574297, 5832.795852145155, 5840.88318233633, 5895.911561326584, 4160.396742472324, 4177.248095342837, 4180.979095104535, 4184.351425529047, 4209.768462734548, 5951.256092988377, 6083.11794789753, 6099.512039036911, 6118.357572454917, 6182.196314479089, 4239.40648309237, 4275.722078064916, 4279.422277992493, 4311.784410779448, 4314.245007123741, 4357.847475338348, 4364.468608207232, 4368.257396242647, 4370.300751879684, 4376.715513491613, 4869.123557759637, 4925.1873440881855, 4960.330355401975, 4967.826126085642, 4975.577075948512, 5844.415558444121, 5880.235569376039, 5885.911711324334, 5928.0, 5950.672713000827, 1491.5972687374565, 1500.197394393994, 1514.174774747765, 1521.0949485056594, 1531.089002043782, 938.6620816506189, 945.8885809174193, 959.5921733263335, 966.9218821014658, 970.1947383671536, 5007.273945277987, 5086.593072554261, 5103.234514822806, 5118.411382690963, 5134.2812006319145, 5360.65155764874, 5366.0, 5375.366070625443, 5393.919091213635, 5430.83707488776, 4799.328094066834, 4887.340209071801, 4959.752012399365, 5129.358830146231, 5158.432572417031, 2865.498537755884, 2876.8561571921737, 2897.1598236511177, 2904.5062339402352, 2907.941841163165, 5404.486913585201, 5479.60270131114, 5507.146392309199, 5562.443755624405, 5587.156238441305, 5264.499872512105, 5430.70131142791, 5464.672119672832, 5473.370562385297, 5554.250176226238, 5503.624093976506, 5559.193916882029, 5587.245721827582, 5589.524890384281, 5608.205374280273, 5125.5899528036925, 5275.973620131875, 5308.248278068306, 5363.390982705095, 5464.508194262536, 5291.7618707159045, 5300.178472336794, 5354.179578943178, 5367.812126575551, 5385.499425373382, 5504.22940788282, 5504.697241651587, 5568.916466252928, 5573.554115670656, 5599.880023995136, 3733.5706393765063, 3883.30100581898, 3898.317794386028, 3900.9609903901733, 3904.9804750976064, 5301.062809653213, 5397.9190312144565, 5445.346558413015, 5844.824655260349, 5970.253718285215, 5884.47039766423, 5888.528917686661, 5914.467697907209, 5925.229720136354, 5949.244972732968, 5846.152307915413, 5899.46904778572, 5923.200367950271, 5925.903707814068, 5934.762609495572, 5534.861628459321, 5771.384012476513, 5780.450857168562, 5783.566232532661, 5808.5643576732755, 5863.413658634103, 5885.264341957255, 5927.199828023162, 5954.558630961285, 5974.6713930732485, 5617.859553511195, 5794.565407594363, 5797.304323481209, 5809.2738407699035, 5818.709064546841], "value": 4304.636698636769, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.525385783961, 852.5225873510834, 856.8029353248753, 867.0202670981789, 869.1742844297914, 13476.326183690813, 14188.858111418882, 14285.285735713225, 14416.855831441682, 14450.927745361258, 12846.807297890538, 12854.871451285484, 12896.290704011275, 12899.484020639187, 12998.025148113882, 671.4192223726491, 803.1647087029482, 809.2271880354277, 810.096742132521, 810.6635746165334, 788.3417346515638, 803.7468197517763, 806.4919100966413, 809.2029351089202, 811.4806523824757, 849.4563479373206, 850.8553545897213, 860.9440386374897, 861.4874149880842, 866.8223014282057, 852.5694524265238, 854.2141230068352, 856.6830272799073, 858.6222062292571, 861.1560670542904, 629.3077614623922, 632.4118569730149, 632.9873402531923, 633.6103296472683, 634.4511997122502, 12944.093913426064, 12956.157849739831, 12984.415701293465, 12990.415431305613, 13012.869871301355, 13643.0, 13977.440902363893, 14018.509352172625, 14099.647508812259, 14172.220527870963, 11123.443827808658, 11228.214025018253, 11347.0, 11382.71543211418, 11410.0, 3867.091233560111, 3878.747881387729, 3934.2721596504452, 4027.7180597358197, 4042.2319759245647, 51741.482585174046, 52109.47890521084, 53093.46906530924, 53176.73411632956, 54225.0, 11051.72370690723, 11069.114470842313, 11211.215214934877, 11223.102151827834, 11305.32168069902, 19148.712769308473, 19305.903470482557, 19405.32081377145, 19717.309894153637, 20516.692249616266, 8158.714444994397, 8166.469179503313, 8205.138460461685, 8236.135205803424, 8303.169683031649, 4644.465886423037, 4772.856814295576, 4802.7838747255755, 4833.758312084382, 4905.803767849317, 3279.7540184485783, 3360.6135294440965, 3379.0, 3380.8985730428126, 3388.4747864081105, 595.0455986029299, 599.8860216558927, 613.1722175063718, 616.2512547255106, 621.3258614403333, 604.6130476494959, 611.6696983628791, 636.2619361540591, 638.7285403703407, 645.6932956845461, 8255.901151674387, 8862.42394244385, 8898.644054237759, 8948.060453652402, 8985.910140898639, 700.8878579427267, 714.3677845107014, 739.5969196787763, 744.0810598910341, 749.2806905370905, 4218.936715949263, 4253.21315556623, 4313.180495705806, 4375.16871794358, 4404.141192467502, 68571.65714171497, 68598.0, 70274.0, 70373.0, 70613.2938670617, 60129.0, 61642.0, 62513.0, 62815.371846280956, 66225.0, 56577.717111415004, 57155.42844571501, 57294.12999894972, 57369.71315143481, 57437.42562574404, 8227.958860205661, 8274.793130171553, 8359.582020899052, 8441.620127094173, 8603.096674849301, 35774.0, 36073.278534429686, 36135.63864361428, 36156.45765313575, 37147.44278835766, 45961.0, 51302.0, 52356.73821630867, 52401.7379913098, 52747.0, 11990.460429280529, 12082.516699331929, 12288.81566776517, 12724.0, 12862.871371286168, 13902.16587004783, 14029.298535073001, 14060.859391405955, 14113.017425134216, 14347.282635868374, 13120.278384688552, 13251.204927704368, 13340.199588024361, 13372.465101396225, 13872.722545549233, 12944.805827912778, 13314.400851961878, 13502.392392342565, 14193.219372934587, 14236.786448203078, 12442.875571244173, 13475.797863032258, 14032.719345613235, 14346.928265358607, 14387.0, 10851.945740271249, 11237.662870113909, 11258.437078146224, 11289.7742045157, 11723.41382930867, 10162.141779788744, 10579.31234469757, 10906.0, 11571.363575003455, 11903.642890713292], "value": 6859.026740499898, "unit": "score"}], "name": "dromaeo_css", "value": 7167.794210902632, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 103.0, 111.0, 112.0, 111.0, 105.0, 110.0, 111.0, 111.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 125.0, 151.0, 204.0, 132.0, 161.0, 149.0, 128.0, 190.0, 127.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 162.0, 163.0, 166.0, 165.0, 168.0, 162.0, 163.0, 160.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 78.0, 162.0, 76.0, 76.0, 74.0, 75.0, 178.0, 76.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 181.0, 91.0, 92.0, 92.0, 153.0, 92.0, 93.0, 149.0], "value": 112.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 57.0, 58.0, 62.0, 57.0, 60.0, 60.0, 114.0, 55.0, 59.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0, 60.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 81.0, 86.0, 81.0, 80.0, 80.0, 79.0, 85.0, 80.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 133.0, 124.0, 124.0, 132.0, 128.0, 123.0, 126.0, 185.0, 126.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 172.0, 167.0, 178.0, 170.0, 169.0, 169.0, 173.0, 168.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 66.0, 69.0, 67.0, 64.0, 68.0, 64.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1562.7, "alertThreshold": 2.0}]} 21:17:59 INFO - Return code: 0 21:17:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:17:59 ERROR - # TBPL SUCCESS # 21:17:59 INFO - Running post-action listener: _resource_record_post_action 21:17:59 INFO - ##### 21:17:59 INFO - ##### Finished run-tests step (success) 21:17:59 INFO - ##### 21:17:59 INFO - Running post-run listener: _resource_record_post_run 21:18:00 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 540672; Write bytes: 290734080; Read time: 404; Write time: 332528 21:18:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5279744; Read time: 0; Write time: 364 21:18:00 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 540672; Write bytes: 285454336; Read time: 404; Write time: 332164 21:18:00 INFO - Running post-run listener: _upload_blobber_files 21:18:00 INFO - Blob upload gear active. 21:18:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:18:00 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 21:18:00 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:18:00 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:18:01 INFO - (blobuploader) - INFO - Open directory for files ... 21:18:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:18:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:18:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:18:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:18:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:18:02 INFO - (blobuploader) - INFO - Done attempting. 21:18:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:18:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:18:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:18:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:18:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:18:03 INFO - (blobuploader) - INFO - Done attempting. 21:18:03 INFO - (blobuploader) - INFO - Iteration through files over. 21:18:03 INFO - Return code: 0 21:18:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:18:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:18:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9fd21c2d712ce305d106a174450bfa953594bc2f6d85aa07c39e6cc99da663e0a2a9e8a8d3055a1e9cb1aea021620bc39d08bb2d7340e9f944213d7b54c0102d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/51ee8cd8a834c0c2252ad9a8d641d85b1142fa3ff84b0e5cdff5dd9c8dd410a85c2530558b943db468d2f5d3b3052a296f743fbc58a1bdbaf3c63ba5cd34699a"} 21:18:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:18:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:18:03 INFO - Contents: 21:18:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9fd21c2d712ce305d106a174450bfa953594bc2f6d85aa07c39e6cc99da663e0a2a9e8a8d3055a1e9cb1aea021620bc39d08bb2d7340e9f944213d7b54c0102d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/51ee8cd8a834c0c2252ad9a8d641d85b1142fa3ff84b0e5cdff5dd9c8dd410a85c2530558b943db468d2f5d3b3052a296f743fbc58a1bdbaf3c63ba5cd34699a"} 21:18:03 INFO - Running post-run listener: copy_logs_to_upload_dir 21:18:03 INFO - Copying logs to upload dir... 21:18:03 INFO - mkdir: /builds/slave/test/build/upload/logs 21:18:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.564394 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-05-20 21:18:03.272454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 21:18:03.275956) ========= 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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9fd21c2d712ce305d106a174450bfa953594bc2f6d85aa07c39e6cc99da663e0a2a9e8a8d3055a1e9cb1aea021620bc39d08bb2d7340e9f944213d7b54c0102d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/51ee8cd8a834c0c2252ad9a8d641d85b1142fa3ff84b0e5cdff5dd9c8dd410a85c2530558b943db468d2f5d3b3052a296f743fbc58a1bdbaf3c63ba5cd34699a"} build_url:https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011731 build_url: 'https://queue.taskcluster.net/v1/task/fB_MBqm7TSqqCka5crdXXw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9fd21c2d712ce305d106a174450bfa953594bc2f6d85aa07c39e6cc99da663e0a2a9e8a8d3055a1e9cb1aea021620bc39d08bb2d7340e9f944213d7b54c0102d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/51ee8cd8a834c0c2252ad9a8d641d85b1142fa3ff84b0e5cdff5dd9c8dd410a85c2530558b943db468d2f5d3b3052a296f743fbc58a1bdbaf3c63ba5cd34699a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 21:18:03.300920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:18:03.301237) ========= 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=eca2ab57546de7b41f256b19000001ef-1463802370.539294-582841384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:18:03.383282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 21:18:03.383602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 21:18:03.383881) ========= ========= Total master_lag: 0.17 =========