builder: larch_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1467327694.68 results: success (0) buildid: 20160630142223 builduid: 4844506a8c974ba9b059bf0782fd6d3f revision: 153145e35ec01ceb3d139910bcc70cf421ee4ce8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.683952) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.684795) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.685329) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.702232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.702850) ========= 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=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-30 16:01:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-06-30 16:01:34 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.807112) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.807677) ========= 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=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016116 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.860244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:34.860982) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 153145e35ec01ceb3d139910bcc70cf421ee4ce8 --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 153145e35ec01ceb3d139910bcc70cf421ee4ce8 --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=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-30 16:01:35,116 truncating revision to first 12 chars 2016-06-30 16:01:35,116 Setting DEBUG logging. 2016-06-30 16:01:35,116 attempt 1/10 2016-06-30 16:01:35,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/153145e35ec0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 16:01:35,416 unpacking tar archive at: larch-153145e35ec0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:35.522120) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:35.522783) ========= script_repo_revision: 153145e35ec01ceb3d139910bcc70cf421ee4ce8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:35.523706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:35.524530) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 16:01:35.538202) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-06-30 16:01:35.538957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:35 INFO - MultiFileLogger online at 20160630 16:01:35 in /builds/slave/test 16:01:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 16:01:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:35 INFO - {'append_to_log': False, 16:01:35 INFO - 'base_work_dir': '/builds/slave/test', 16:01:35 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 16:01:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:35 INFO - 'branch': 'Larch-Non-PGO', 16:01:35 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:35 INFO - 'config_files': ('talos/linux_config.py',), 16:01:35 INFO - 'default_actions': ('clobber', 16:01:35 INFO - 'read-buildbot-config', 16:01:35 INFO - 'download-and-extract', 16:01:35 INFO - 'populate-webroot', 16:01:35 INFO - 'create-virtualenv', 16:01:35 INFO - 'install', 16:01:35 INFO - 'run-tests'), 16:01:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:35 INFO - 'download_minidump_stackwalk': True, 16:01:35 INFO - 'download_symbols': 'ondemand', 16:01:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:35 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:35 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:35 INFO - 'installer_path': 'installer.exe', 16:01:35 INFO - 'log_level': 'info', 16:01:35 INFO - 'log_name': 'talos', 16:01:35 INFO - 'log_to_console': True, 16:01:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:35 INFO - 'opt_config_files': (), 16:01:35 INFO - 'pip_index': False, 16:01:35 INFO - 'sps_profile': False, 16:01:35 INFO - 'sps_profile_interval': 0, 16:01:35 INFO - 'suite': 'dromaeojs-e10s', 16:01:35 INFO - 'system_bits': '32', 16:01:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:35 INFO - 'title': 'talos-linux64-ix-053', 16:01:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:35 INFO - 'use_talos_json': True, 16:01:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:35 INFO - 'work_dir': 'build'} 16:01:35 INFO - ##### 16:01:35 INFO - ##### Running clobber step. 16:01:35 INFO - ##### 16:01:35 INFO - Running pre-action listener: _resource_record_pre_action 16:01:35 INFO - Running main action method: clobber 16:01:35 INFO - rmtree: /builds/slave/test/build 16:01:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:36 INFO - Running post-action listener: _resource_record_post_action 16:01:36 INFO - ##### 16:01:36 INFO - ##### Running read-buildbot-config step. 16:01:36 INFO - ##### 16:01:36 INFO - Running pre-action listener: _resource_record_pre_action 16:01:36 INFO - Running main action method: read_buildbot_config 16:01:36 INFO - Using buildbot properties: 16:01:36 INFO - { 16:01:36 INFO - "project": "", 16:01:36 INFO - "product": "firefox", 16:01:36 INFO - "script_repo_revision": "production", 16:01:36 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs-e10s", 16:01:36 INFO - "repository": "", 16:01:36 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs-e10s", 16:01:36 INFO - "buildid": "20160630142223", 16:01:36 INFO - "pgo_build": "False", 16:01:36 INFO - "basedir": "/builds/slave/test", 16:01:36 INFO - "buildnumber": 1, 16:01:36 INFO - "slavename": "talos-linux64-ix-053", 16:01:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:01:36 INFO - "platform": "ubuntu64_hw", 16:01:36 INFO - "scheduler": "tests-larch-linux64-talos", 16:01:36 INFO - "branch": "larch", 16:01:36 INFO - "repo_path": "projects/larch", 16:01:36 INFO - "revision": "153145e35ec01ceb3d139910bcc70cf421ee4ce8", 16:01:36 INFO - "stage_platform": "linux64", 16:01:36 INFO - "builduid": "4844506a8c974ba9b059bf0782fd6d3f", 16:01:36 INFO - "slavebuilddir": "test" 16:01:36 INFO - } 16:01:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2. 16:01:36 INFO - Running post-action listener: _resource_record_post_action 16:01:36 INFO - ##### 16:01:36 INFO - ##### Running download-and-extract step. 16:01:36 INFO - ##### 16:01:36 INFO - Running pre-action listener: _resource_record_pre_action 16:01:36 INFO - Running main action method: download_and_extract 16:01:36 INFO - mkdir: /builds/slave/test/build/tests 16:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:36 INFO - https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json 16:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json 16:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json 16:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:01:37 INFO - Downloaded 1448 bytes. 16:01:37 INFO - Reading from file /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.test_packages.json 16:01:37 INFO - Using the following test package requirements: 16:01:37 INFO - {u'common': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'], 16:01:37 INFO - u'cppunittest': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:01:37 INFO - u'gtest': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.gtest.tests.zip'], 16:01:37 INFO - u'jittest': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'jsshell-linux-x86_64.zip'], 16:01:37 INFO - u'mochitest': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.mochitest.tests.zip'], 16:01:37 INFO - u'mozbase': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'], 16:01:37 INFO - u'reftest': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.reftest.tests.zip'], 16:01:37 INFO - u'talos': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip'], 16:01:37 INFO - u'web-platform': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.web-platform.tests.zip'], 16:01:37 INFO - u'webapprt': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'], 16:01:37 INFO - u'xpcshell': [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 16:01:37 INFO - u'firefox-47.0.2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:01:37 INFO - Downloading packages: [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:37 INFO - https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:01:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:38 INFO - Downloaded 22140718 bytes. 16:01:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:01:39 INFO - Return code: 0 16:01:39 INFO - Downloading packages: [u'firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:01: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')]} 16:01:39 INFO - https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip 16:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:01:40 INFO - Downloaded 22140718 bytes. 16:01:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:01:42 INFO - Return code: 0 16:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:42 INFO - https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip 16:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip 16:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip 16:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:01:43 INFO - Downloaded 10845194 bytes. 16:01:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:01:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:01:43 INFO - Return code: 0 16:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:43 INFO - https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 16:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 16:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 16:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:01:44 INFO - Downloaded 59007382 bytes. 16:01:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 16:01:44 INFO - mkdir: /builds/slave/test/properties 16:01:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:44 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:44 INFO - Contents: 16:01:44 INFO - build_url:https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 16:01:45 INFO - Running post-action listener: _resource_record_post_action 16:01:45 INFO - Running post-action listener: set_extra_try_arguments 16:01:45 INFO - ##### 16:01:45 INFO - ##### Running populate-webroot step. 16:01:45 INFO - ##### 16:01:45 INFO - Running pre-action listener: _resource_record_pre_action 16:01:45 INFO - Running main action method: populate_webroot 16:01:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:45 INFO - '--tppagecycles', 16:01:45 INFO - '7'], 16:01:45 INFO - 'tests': ['tsvgm']}}, 16:01:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:01:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:01:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'tests': ['damp', 'tps']}, 16:01:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['damp', 'tps']}, 16:01:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:01:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:45 INFO - 'other': {'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'other_l64': {'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'other_nol64': {'tests': ['a11yr', 16:01:45 INFO - 'ts_paint', 16:01:45 INFO - 'tpaint', 16:01:45 INFO - 'sessionrestore', 16:01:45 INFO - 'sessionrestore_no_auto_restore', 16:01:45 INFO - 'tabpaint']}, 16:01:45 INFO - 'svgr': {'tests': ['tsvgx', 16:01:45 INFO - 'tsvgr_opacity', 16:01:45 INFO - 'tart', 16:01:45 INFO - 'tscrollx', 16:01:45 INFO - 'cart']}, 16:01:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['tsvgx', 16:01:45 INFO - 'tsvgr_opacity', 16:01:45 INFO - 'tart', 16:01:45 INFO - 'tscrollx', 16:01:45 INFO - 'cart']}, 16:01:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'tests': ['tp5o']}, 16:01:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'talos_options': ['--e10s'], 16:01:45 INFO - 'tests': ['tp5o']}, 16:01:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'talos_options': ['--xperf_path', 16:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:45 INFO - 'tests': ['tp5n']}, 16:01:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:45 INFO - 'talos_options': ['--e10s', 16:01:45 INFO - '--xperf_path', 16:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:45 INFO - 'tests': ['tp5n']}}, 16:01:45 INFO - 'talos.zip': {'path': '', 16:01:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:45 INFO - Running post-action listener: _resource_record_post_action 16:01:45 INFO - ##### 16:01:45 INFO - ##### Running create-virtualenv step. 16:01:45 INFO - ##### 16:01:45 INFO - Running pre-action listener: _resource_record_pre_action 16:01:45 INFO - Running main action method: create_virtualenv 16:01:45 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:45 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 16:01:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:45 INFO - Using real prefix '/usr' 16:01:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:46 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:48 INFO - Installing pip.................done. 16:01:48 INFO - Return code: 0 16:01:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:48 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')]} 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 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')]} 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:01:48 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 16:01:48 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 16:01:48 INFO - Using env: {'DISPLAY': ':0', 16:01:48 INFO - 'HOME': '/home/cltbld', 16:01:48 INFO - 'LANG': 'en_US.UTF-8', 16:01:48 INFO - 'LANGUAGE': 'en_US:en', 16:01:48 INFO - 'LOGNAME': 'cltbld', 16:01:48 INFO - 'MAIL': '/var/mail/cltbld', 16:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:48 INFO - 'MOZ_NO_REMOTE': '1', 16:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:48 INFO - 'NO_EM_RESTART': '1', 16:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:48 INFO - 'PWD': '/builds/slave/test', 16:01:48 INFO - 'SHELL': '/bin/bash', 16:01:48 INFO - 'SHLVL': '1', 16:01:48 INFO - 'TERM': 'linux', 16:01:48 INFO - 'TMOUT': '86400', 16:01:48 INFO - 'USER': 'cltbld', 16:01:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:48 INFO - '_': '/tools/buildbot/bin/python'} 16:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:48 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 16:01:48 INFO - Cleaning up... 16:01:48 INFO - Return code: 0 16:01:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:01:48 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')]} 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 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')]} 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:48 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:01:48 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 16:01:48 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 16:01:48 INFO - Using env: {'DISPLAY': ':0', 16:01:48 INFO - 'HOME': '/home/cltbld', 16:01:48 INFO - 'LANG': 'en_US.UTF-8', 16:01:48 INFO - 'LANGUAGE': 'en_US:en', 16:01:48 INFO - 'LOGNAME': 'cltbld', 16:01:48 INFO - 'MAIL': '/var/mail/cltbld', 16:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:48 INFO - 'MOZ_NO_REMOTE': '1', 16:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:48 INFO - 'NO_EM_RESTART': '1', 16:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:48 INFO - 'PWD': '/builds/slave/test', 16:01:48 INFO - 'SHELL': '/bin/bash', 16:01:48 INFO - 'SHLVL': '1', 16:01:48 INFO - 'TERM': 'linux', 16:01:48 INFO - 'TMOUT': '86400', 16:01:48 INFO - 'USER': 'cltbld', 16:01:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:48 INFO - '_': '/tools/buildbot/bin/python'} 16:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:48 INFO - Downloading/unpacking psutil>=0.7.1 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:50 INFO - Installing collected packages: psutil 16:01:50 INFO - Running setup.py install for psutil 16:01:50 INFO - building 'psutil._psutil_linux' extension 16:01:50 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 16:01:50 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 16:01:50 INFO - building 'psutil._psutil_posix' extension 16:01:50 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 16:01:51 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 16:01:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:51 INFO - Successfully installed psutil 16:01:51 INFO - Cleaning up... 16:01:51 INFO - Return code: 0 16:01:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:01:51 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')]} 16:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:51 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')]} 16:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:51 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:01:51 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 16:01:51 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 16:01:51 INFO - Using env: {'DISPLAY': ':0', 16:01:51 INFO - 'HOME': '/home/cltbld', 16:01:51 INFO - 'LANG': 'en_US.UTF-8', 16:01:51 INFO - 'LANGUAGE': 'en_US:en', 16:01:51 INFO - 'LOGNAME': 'cltbld', 16:01:51 INFO - 'MAIL': '/var/mail/cltbld', 16:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:51 INFO - 'MOZ_NO_REMOTE': '1', 16:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:51 INFO - 'NO_EM_RESTART': '1', 16:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:51 INFO - 'PWD': '/builds/slave/test', 16:01:51 INFO - 'SHELL': '/bin/bash', 16:01:51 INFO - 'SHLVL': '1', 16:01:51 INFO - 'TERM': 'linux', 16:01:51 INFO - 'TMOUT': '86400', 16:01:51 INFO - 'USER': 'cltbld', 16:01:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:51 INFO - '_': '/tools/buildbot/bin/python'} 16:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:01:51 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 16:01:51 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 16:01:51 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 16:01:51 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 16:01:51 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 16:01:51 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 16:01:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:01:53 INFO - Installing collected packages: mozsystemmonitor 16:01:53 INFO - Running setup.py install for mozsystemmonitor 16:01:53 INFO - Successfully installed mozsystemmonitor 16:01:53 INFO - Cleaning up... 16:01:53 INFO - Return code: 0 16:01:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01: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')]} 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:01:53 INFO - Using env: {'DISPLAY': ':0', 16:01:53 INFO - 'HOME': '/home/cltbld', 16:01:53 INFO - 'LANG': 'en_US.UTF-8', 16:01:53 INFO - 'LANGUAGE': 'en_US:en', 16:01:53 INFO - 'LOGNAME': 'cltbld', 16:01:53 INFO - 'MAIL': '/var/mail/cltbld', 16:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:53 INFO - 'MOZ_NO_REMOTE': '1', 16:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:53 INFO - 'NO_EM_RESTART': '1', 16:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:53 INFO - 'PWD': '/builds/slave/test', 16:01:53 INFO - 'SHELL': '/bin/bash', 16:01:53 INFO - 'SHLVL': '1', 16:01:53 INFO - 'TERM': 'linux', 16:01:53 INFO - 'TMOUT': '86400', 16:01:53 INFO - 'USER': 'cltbld', 16:01:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:53 INFO - '_': '/tools/buildbot/bin/python'} 16:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:56 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 16:01:56 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 16:01:56 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 16:01:56 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 16:01:56 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 16:01:56 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 16:01:56 INFO - Downloading docopt-0.6.1.tar.gz 16:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:56 INFO - Running setup.py install for blobuploader 16:01:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:56 INFO - Running setup.py install for requests 16:01:56 INFO - Running setup.py install for docopt 16:01:56 INFO - Successfully installed blobuploader requests docopt 16:01:56 INFO - Cleaning up... 16:01:56 INFO - Return code: 0 16:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:57 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:01:57 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 16:01:57 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 16:01:57 INFO - Using env: {'DISPLAY': ':0', 16:01:57 INFO - 'HOME': '/home/cltbld', 16:01:57 INFO - 'LANG': 'en_US.UTF-8', 16:01:57 INFO - 'LANGUAGE': 'en_US:en', 16:01:57 INFO - 'LOGNAME': 'cltbld', 16:01:57 INFO - 'MAIL': '/var/mail/cltbld', 16:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:57 INFO - 'MOZ_NO_REMOTE': '1', 16:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:57 INFO - 'NO_EM_RESTART': '1', 16:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:57 INFO - 'PWD': '/builds/slave/test', 16:01:57 INFO - 'SHELL': '/bin/bash', 16:01:57 INFO - 'SHLVL': '1', 16:01:57 INFO - 'TERM': 'linux', 16:01:57 INFO - 'TMOUT': '86400', 16:01:57 INFO - 'USER': 'cltbld', 16:01:57 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:57 INFO - '_': '/tools/buildbot/bin/python'} 16:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:57 INFO - Running setup.py (path:/tmp/pip-9kW0Xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:57 INFO - Running setup.py (path:/tmp/pip-me0m_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:57 INFO - Running setup.py (path:/tmp/pip-2yhMVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:57 INFO - Running setup.py (path:/tmp/pip-NnjsAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:57 INFO - Running setup.py (path:/tmp/pip-KotPvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:57 INFO - Running setup.py (path:/tmp/pip-nuzKox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:57 INFO - Running setup.py (path:/tmp/pip-MRmWCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:57 INFO - Running setup.py (path:/tmp/pip-P2Wauo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:57 INFO - Running setup.py (path:/tmp/pip-zUcO8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:57 INFO - Running setup.py (path:/tmp/pip-p1tqEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:57 INFO - Running setup.py (path:/tmp/pip-Lunm0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:57 INFO - Running setup.py (path:/tmp/pip-PtpLvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:58 INFO - Running setup.py (path:/tmp/pip-w5BwW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:58 INFO - Running setup.py (path:/tmp/pip-G_vDf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:58 INFO - Running setup.py (path:/tmp/pip-k_k9EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:58 INFO - Running setup.py (path:/tmp/pip-duypCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:58 INFO - Running setup.py (path:/tmp/pip-0TSeSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:58 INFO - Running setup.py install for manifestparser 16:01:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozcrash 16:01:58 INFO - Running setup.py install for mozdebug 16:01:58 INFO - Running setup.py install for mozdevice 16:01:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:58 INFO - Running setup.py install for mozfile 16:01:58 INFO - Running setup.py install for mozhttpd 16:01:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozinfo 16:01:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozInstall 16:01:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozleak 16:01:59 INFO - Running setup.py install for mozlog 16:01:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for moznetwork 16:01:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozprocess 16:01:59 INFO - Running setup.py install for mozprofile 16:01:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozrunner 16:01:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozscreenshot 16:01:59 INFO - Running setup.py install for moztest 16:02:00 INFO - Running setup.py install for mozversion 16:02:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:00 INFO - Cleaning up... 16:02:00 INFO - Return code: 0 16:02:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02: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')]} 16:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:00 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:02:00 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 16:02:00 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 16:02:00 INFO - Using env: {'DISPLAY': ':0', 16:02:00 INFO - 'HOME': '/home/cltbld', 16:02:00 INFO - 'LANG': 'en_US.UTF-8', 16:02:00 INFO - 'LANGUAGE': 'en_US:en', 16:02:00 INFO - 'LOGNAME': 'cltbld', 16:02:00 INFO - 'MAIL': '/var/mail/cltbld', 16:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:00 INFO - 'MOZ_NO_REMOTE': '1', 16:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:00 INFO - 'NO_EM_RESTART': '1', 16:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:00 INFO - 'PWD': '/builds/slave/test', 16:02:00 INFO - 'SHELL': '/bin/bash', 16:02:00 INFO - 'SHLVL': '1', 16:02:00 INFO - 'TERM': 'linux', 16:02:00 INFO - 'TMOUT': '86400', 16:02:00 INFO - 'USER': 'cltbld', 16:02:00 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:00 INFO - '_': '/tools/buildbot/bin/python'} 16:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:00 INFO - Running setup.py (path:/tmp/pip-HXEtd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:00 INFO - Running setup.py (path:/tmp/pip-HoV_lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:00 INFO - Running setup.py (path:/tmp/pip-kbSe57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:00 INFO - Running setup.py (path:/tmp/pip-qJDP0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:00 INFO - Running setup.py (path:/tmp/pip-5ptcF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:00 INFO - Running setup.py (path:/tmp/pip-SOG6F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:00 INFO - Running setup.py (path:/tmp/pip-CKNwn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:00 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)) 16:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:00 INFO - Running setup.py (path:/tmp/pip-qLYJIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:01 INFO - Running setup.py (path:/tmp/pip-hrQ_I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:01 INFO - Running setup.py (path:/tmp/pip-LU9XwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:01 INFO - Running setup.py (path:/tmp/pip-IcCl12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:01 INFO - Running setup.py (path:/tmp/pip-x9kxVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:01 INFO - Running setup.py (path:/tmp/pip-6vnMxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:01 INFO - Running setup.py (path:/tmp/pip-O3ZbKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:01 INFO - Running setup.py (path:/tmp/pip-jpGLqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:01 INFO - Running setup.py (path:/tmp/pip-G8V4MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:01 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)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:01 INFO - Running setup.py (path:/tmp/pip-YbPQ4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:01 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)) 16:02:01 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)) 16:02:01 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)) 16:02:01 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)) 16:02:01 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)) 16:02:01 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)) 16:02:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02: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 16:02: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 16:02: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 16:02: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 16:02: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 16:02: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 16:02:03 INFO - Downloading blessings-1.6.tar.gz 16:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:03 INFO - Installing collected packages: blessings 16:02:03 INFO - Running setup.py install for blessings 16:02:03 INFO - Successfully installed blessings 16:02:03 INFO - Cleaning up... 16:02:03 INFO - Return code: 0 16:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:04 INFO - Reading from file tmpfile_stdout 16:02:04 INFO - Current package versions: 16:02:04 INFO - argparse == 1.2.1 16:02:04 INFO - blessings == 1.6 16:02:04 INFO - blobuploader == 1.2.4 16:02:04 INFO - docopt == 0.6.1 16:02:04 INFO - manifestparser == 1.1 16:02:04 INFO - mozInstall == 1.12 16:02:04 INFO - mozcrash == 0.17 16:02:04 INFO - mozdebug == 0.1 16:02:04 INFO - mozdevice == 0.48 16:02:04 INFO - mozfile == 1.2 16:02:04 INFO - mozhttpd == 0.7 16:02:04 INFO - mozinfo == 0.9 16:02:04 INFO - mozleak == 0.1 16:02:04 INFO - mozlog == 3.1 16:02:04 INFO - moznetwork == 0.27 16:02:04 INFO - mozprocess == 0.22 16:02:04 INFO - mozprofile == 0.28 16:02:04 INFO - mozrunner == 6.11 16:02:04 INFO - mozscreenshot == 0.1 16:02:04 INFO - mozsystemmonitor == 0.0 16:02:04 INFO - moztest == 0.7 16:02:04 INFO - mozversion == 1.4 16:02:04 INFO - psutil == 3.1.1 16:02:04 INFO - requests == 1.2.3 16:02:04 INFO - wsgiref == 0.1.2 16:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:02:04 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 16:02:04 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 16:02:04 INFO - Using env: {'DISPLAY': ':0', 16:02:04 INFO - 'HOME': '/home/cltbld', 16:02:04 INFO - 'LANG': 'en_US.UTF-8', 16:02:04 INFO - 'LANGUAGE': 'en_US:en', 16:02:04 INFO - 'LOGNAME': 'cltbld', 16:02:04 INFO - 'MAIL': '/var/mail/cltbld', 16:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:04 INFO - 'MOZ_NO_REMOTE': '1', 16:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:04 INFO - 'NO_EM_RESTART': '1', 16:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:04 INFO - 'PWD': '/builds/slave/test', 16:02:04 INFO - 'SHELL': '/bin/bash', 16:02:04 INFO - 'SHLVL': '1', 16:02:04 INFO - 'TERM': 'linux', 16:02:04 INFO - 'TMOUT': '86400', 16:02:04 INFO - 'USER': 'cltbld', 16:02:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:04 INFO - '_': '/tools/buildbot/bin/python'} 16:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 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)) 16:02:04 INFO - Cleaning up... 16:02:04 INFO - Return code: 0 16:02:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:04 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 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:02:04 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 16:02:04 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 16:02:04 INFO - Using env: {'DISPLAY': ':0', 16:02:04 INFO - 'HOME': '/home/cltbld', 16:02:04 INFO - 'LANG': 'en_US.UTF-8', 16:02:04 INFO - 'LANGUAGE': 'en_US:en', 16:02:04 INFO - 'LOGNAME': 'cltbld', 16:02:04 INFO - 'MAIL': '/var/mail/cltbld', 16:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:04 INFO - 'MOZ_NO_REMOTE': '1', 16:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:04 INFO - 'NO_EM_RESTART': '1', 16:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:04 INFO - 'PWD': '/builds/slave/test', 16:02:04 INFO - 'SHELL': '/bin/bash', 16:02:04 INFO - 'SHLVL': '1', 16:02:04 INFO - 'TERM': 'linux', 16:02:04 INFO - 'TMOUT': '86400', 16:02:04 INFO - 'USER': 'cltbld', 16:02:04 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:04 INFO - '_': '/tools/buildbot/bin/python'} 16:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:04 INFO - Downloading/unpacking jsonschema 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:06 INFO - Downloading/unpacking functools32 (from jsonschema) 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:06 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:06 INFO - warning: no files found matching '*.txt' 16:02:06 INFO - no previously-included directories found matching 'build' 16:02:06 INFO - no previously-included directories found matching 'dist' 16:02:06 INFO - no previously-included directories found matching '.git*' 16:02:06 INFO - Installing collected packages: jsonschema, functools32 16:02:06 INFO - Running setup.py install for functools32 16:02:06 INFO - warning: no files found matching '*.txt' 16:02:06 INFO - no previously-included directories found matching 'build' 16:02:06 INFO - no previously-included directories found matching 'dist' 16:02:06 INFO - no previously-included directories found matching '.git*' 16:02:06 INFO - Successfully installed jsonschema functools32 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa341b5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d3410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, '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': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', '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 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:02:06 INFO - Using env: {'DISPLAY': ':0', 16:02:06 INFO - 'HOME': '/home/cltbld', 16:02:06 INFO - 'LANG': 'en_US.UTF-8', 16:02:06 INFO - 'LANGUAGE': 'en_US:en', 16:02:06 INFO - 'LOGNAME': 'cltbld', 16:02:06 INFO - 'MAIL': '/var/mail/cltbld', 16:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:06 INFO - 'MOZ_NO_REMOTE': '1', 16:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:06 INFO - 'NO_EM_RESTART': '1', 16:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:06 INFO - 'PWD': '/builds/slave/test', 16:02:06 INFO - 'SHELL': '/bin/bash', 16:02:06 INFO - 'SHLVL': '1', 16:02:06 INFO - 'TERM': 'linux', 16:02:06 INFO - 'TMOUT': '86400', 16:02:06 INFO - 'USER': 'cltbld', 16:02:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:06 INFO - '_': '/tools/buildbot/bin/python'} 16:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:07 INFO - Downloading/unpacking flake8 16:02:07 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 16:02:07 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 16:02:07 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 16:02:07 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 16:02:07 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 16:02:07 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 16:02:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:02:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:02:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:09 INFO - no previously-included directories found matching 'docs/_build' 16:02:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:02:09 INFO - Running setup.py install for pep8 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:02:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:02:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:02:09 INFO - no previously-included directories found matching 'docs/_build' 16:02:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:02:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:02:09 INFO - Cleaning up... 16:02:09 INFO - Return code: 0 16:02:09 INFO - Running post-action listener: _resource_record_post_action 16:02:09 INFO - Running post-action listener: _start_resource_monitoring 16:02:10 INFO - Starting resource monitoring. 16:02:10 INFO - ##### 16:02:10 INFO - ##### Running install step. 16:02:10 INFO - ##### 16:02:10 INFO - Running pre-action listener: _resource_record_pre_action 16:02:10 INFO - Running main action method: install 16:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:10 INFO - Reading from file tmpfile_stdout 16:02:10 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:10 INFO - Reading from file tmpfile_stdout 16:02:10 INFO - Output received: 16:02:10 INFO - Usage: mozinstall [options] installer 16:02:10 INFO - Options: 16:02:10 INFO - -h, --help show this help message and exit 16:02:10 INFO - -d DEST, --destination=DEST 16:02:10 INFO - Directory to install application into. [default: 16:02:10 INFO - "/builds/slave/test"] 16:02:10 INFO - --app=APP Application being installed. [default: firefox] 16:02:10 INFO - mkdir: /builds/slave/test/build/application 16:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:02:25 INFO - Reading from file tmpfile_stdout 16:02:25 INFO - Output received: 16:02:25 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:25 INFO - Running post-action listener: _resource_record_post_action 16:02:25 INFO - ##### 16:02:25 INFO - ##### Running run-tests step. 16:02:25 INFO - ##### 16:02:25 INFO - Running pre-action listener: _resource_record_pre_action 16:02:25 INFO - Running main action method: run_tests 16:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:25 INFO - Python 2.7.3 16:02:25 INFO - Return code: 0 16:02:25 INFO - grabbing minidump binary from tooltool 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:25 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1662160>, '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 0x1663060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16634f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:25 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 16:02:25 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 16:02:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:25 INFO - Return code: 0 16:02:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:02:26 INFO - Return code: 0 16:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 16:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 16:02:26 INFO - Using env: {'DISPLAY': ':0', 16:02:26 INFO - 'HOME': '/home/cltbld', 16:02:26 INFO - 'LANG': 'en_US.UTF-8', 16:02:26 INFO - 'LANGUAGE': 'en_US:en', 16:02:26 INFO - 'LOGNAME': 'cltbld', 16:02:26 INFO - 'MAIL': '/var/mail/cltbld', 16:02:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:26 INFO - 'MOZ_NO_REMOTE': '1', 16:02:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:26 INFO - 'NO_EM_RESTART': '1', 16:02:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:26 INFO - 'PWD': '/builds/slave/test', 16:02:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:26 INFO - 'SHELL': '/bin/bash', 16:02:26 INFO - 'SHLVL': '1', 16:02:26 INFO - 'TERM': 'linux', 16:02:26 INFO - 'TMOUT': '86400', 16:02:26 INFO - 'USER': 'cltbld', 16:02:26 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832', 16:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:26 INFO - '_': '/tools/buildbot/bin/python'} 16:02:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 16:02:26 INFO - mozversion application_buildid: 20160630142223 16:02:26 INFO - mozversion application_changeset: 153145e35ec01ceb3d139910bcc70cf421ee4ce8 16:02:26 INFO - mozversion application_display_name: Nightly 16:02:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:26 INFO - mozversion application_name: Firefox 16:02:26 INFO - mozversion application_remotingname: firefox 16:02:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:02:26 INFO - mozversion application_vendor: Mozilla 16:02:26 INFO - mozversion application_version: 47.0.2 16:02:26 INFO - mozversion platform_buildid: 20160630142223 16:02:26 INFO - mozversion platform_changeset: 153145e35ec01ceb3d139910bcc70cf421ee4ce8 16:02:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:02:26 INFO - mozversion platform_version: 47.0.2 16:02:26 INFO - using testdate: 1467327746 16:02:26 INFO - actual date: 1467327746 16:02:26 INFO - starting webserver on 'localhost:53497' 16:02:26 INFO - SUITE-START | Running 2 tests 16:02:26 INFO - TEST-START | dromaeo_css 16:02:26 INFO - operating with platform_type : linux_ 16:02:26 INFO - Initialising browser for dromaeo_css test... 16:02:26 INFO - TEST-INFO | started process 20993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt0ib8l/profile http://localhost:53497/getInfo.html) 16:02:30 INFO - PROCESS | 20993 | 16:02:30 INFO - PROCESS | 20993 | (process:21069): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:02:31 INFO - PROCESS | 20993 | __metrics Screen width/height:1600/1200 16:02:31 INFO - PROCESS | 20993 | colorDepth:24 16:02:31 INFO - PROCESS | 20993 | Browser inner width/height: 1024/697 16:02:31 INFO - PROCESS | 20993 | __metrics 16:02:32 ERROR - PROCESS | 20993 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 16:02:32 INFO - TEST-INFO | 20993: exit 0 16:02:32 INFO - Browser initialized. 16:02:32 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:32 INFO - TEST-INFO | started process 21119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt0ib8l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:34 INFO - PROCESS | 21119 | 16:02:34 INFO - PROCESS | 21119 | (process:21186): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:02:44 INFO - PROCESS | 21119 | RSS: Main: 149843968 16:02:44 INFO - PROCESS | 21119 | 16:03:34 INFO - PROCESS | 21119 | 1467327814282 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] 16:05:45 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53497/tests/dromaeo/cssquery-ext.html) 16:05:46 INFO - PROCESS | 21119 | RSS: Main: 152612864 16:05:46 INFO - PROCESS | 21119 | 16:08:48 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-ext.html (next: http://localhost:53497/tests/dromaeo/cssquery-jquery.html) 16:08:49 INFO - PROCESS | 21119 | RSS: Main: 151756800 16:08:49 INFO - PROCESS | 21119 | 16:11:50 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53497/tests/dromaeo/cssquery-mootools.html) 16:11:51 INFO - PROCESS | 21119 | RSS: Main: 151470080 16:11:51 INFO - PROCESS | 21119 | 16:14:52 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53497/tests/dromaeo/cssquery-prototype.html) 16:14:53 INFO - PROCESS | 21119 | RSS: Main: 151834624 16:14:53 INFO - PROCESS | 21119 | 16:17:55 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53497/tests/dromaeo/cssquery-yui.html) 16:17:56 INFO - PROCESS | 21119 | RSS: Main: 151687168 16:17:56 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-yui.html (next: http://localhost:53497/tests/dromaeo/cssquery-dojo.html) 16:20:58 INFO - PROCESS | 21119 | RSS: Main: 153284608 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | __start_tp_report 16:20:58 INFO - PROCESS | 21119 | _x_x_mozilla_page_load 16:20:58 INFO - PROCESS | 21119 | _x_x_mozilla_page_load_details 16:20:58 INFO - PROCESS | 21119 | |i|pagename|runs| 16:20:58 INFO - PROCESS | 21119 | |0;dojo.html;2796.482650709619;2840.403515261795;2873.080614203453;2907.462119507891;2919.474494590973;4718.598919091877;4721.3390125382475;4721.976390118049;4724.31497432872;4770.4513980892125;4733.8343157989475;4747.335373047776;4754.16802059639;4768.523147685239;4781.641376896738;4354.521002689694;4372.5627437256335;4382.693211475198;4390.604845563898;4420.889477763066;4223.387608796723;4371.235033869076;4416.425864637593;4428.734275943438;4447.911041779163;4422.756748378838;4441.111777644468;4492.573205545468;4511.120331535352;4519.502854685982;4433.046894917576;4447.355133505656;4451.755153466558;4456.041950980539;4496.033352829141;3940.704447166474;4356.520782713899;4406.317020861771;4407.294832826765;4433.2685106957315;4642.930356044662;4656.674032817704;4669.252919532859;4681.976590117061;4696.178168820477;5083.822066227701;5085.491450854922;5102;5111.2333150027425;5163.044836705184;4885.120678277908;4998.450170481244;5044.772985215674;5066.949330506684;5072.797088116471;4692;4694.788734506956;4699.553542413465;4700.623950083985;4716.740579268105;4932.654714170046;5172.224166374998;5180.637355385163;5184.792608295701;5199.818006369759;5015.448300686921;5020.598352131894;5032.3206367139965;5066.898662026739;5094.363204599425;5079.187330027178;5100.974495127501;5120.564751996122;5125.1799712045895;5137.794488220504;5043.394792624908;5051.3433253676785;5055.014272216956;5065.062963351792;5102.69383836971;4999;5013.24801279811;5020.422651395063;5025.321581586512;5053.242013697901;4277.315629499328;4287;4306.633936115402;4314.22343978087;4320.935185972213;3774.867879624255;3803.6386543278345;3812.3519001769764;3831.750936189139;3854.6530812227033;4370.584794444522;4480.14877173336;4485.349624304457;4513.526079761577;4555.2711566149255;5087.64386492942;5136.794528218829;5146.202338637517;5146.382434107931;5193.039287731782;4378.08060307339;4452.933206001912;4495.707778994421;4513.774311284423;4517.141743068806;4514.751688657089;4528.41130653013;4531.637469002472;4533.252013417815;4544.704594201433;4801.975990120027;4841.2496063110975;4842.4189097307135;4875.902481950341;4900.093482705711;5105.795768169231;5110.284560161506;5115.386153661436;5139.023585518739;5168.793248270027;4876.95123048765;4885.364902562644;4925;4935.062338155739;4959;4796.9280460792415;4975.776090076028;5037.622178336566;5041.823536176216;5089.8727531812;5103.821366252163;5112.71880046601;5124.052050370693;5126.871828204325;5132.820351287687;4982.227754697883;4983.127952608352;4984.900301994012;4994.750262486788;5003.44962054181;4957.529034741694;4966.900661986668;5018.322526458881;5032.7483625819295;5041.2438134280155;4780.545848144421;4817.470078291315;4825.3485779420735;4830.565249127595;4840.12264564175;4781.187198176249;4971.975140124277;4985.030982385279;4985.725785081855;4993.700377977333;4781.354517140142;4849.345338379415;4853.708777473363;4872.878178045577;4877.926831097466 16:20:58 INFO - PROCESS | 21119 | |1;ext.html;551.0769461152569;551.6827824001199;561.7051048199695;562.3364429972634;567.662240966625;33969.66030339696;34742.30515389694;34773.65226347742;34841.3031739365;34964.47553286699;34603.30793384131;34992.65007349926;35016;35056.824715876384;35217.295654086905;7816.609169541514;8031.759047228563;8035.357171426272;8061.395395345343;8062.475939063971;7792.142864284952;8160.795980100485;8166.142555031725;8167.060788009396;8177.182281771835;8097.392695547857;8188.631511581993;8194.795130121734;8217;8242.052164001158;8051.798705032392;8097.149799271051;8146.75559733209;8152.062512811014;8160.26557609818;8088.150744171895;8268.917310826935;8271.669133234664;8271.834563308761;8290.709825156091;31743.20641983946;31872.840635796903;31984.360312793615;32230.83884580562;32253.35493290121;2304.781045800646;2704.310400847791;2903.54994975777;3013.126193403932;3088.3051313454293;26490.867545662342;26803.19590412291;26856.328591785168;27005.459890802074;27314;3087.1356020314347;3097.6282846058393;3121.3133110715607;3167.8099314041;3196.9840150799328;151254;152120;152143;152668.236658816;153061;55297;55557.44442555604;55595.44404555984;55936.720316398154;56069.71965140148;59070.40929590735;59142.40857591455;59277;65769.34230657728;67406.66296668584;13294.401751921187;13367;13625.114367566077;13641.181529108286;13663.795043074362;10106.39361638304;10115.342502737298;10178.18574514037;10187.694369168938;10288.845667314996;20628.484287892923;21186.470338241666;21345.25291614786;21360.57278854414;21401.892990534947;7339;7424.220456852061;7523.435742319348;7736.6518506667335;7787.805304867424;749.2544917806817;942.3403617467663;945.8486642137225;975.512243878061;1119.5074167366333;27400.775917594845;27591.86204069007;27818.3045423862;28276.85861570679;28288.575671364946;971.2375785008876;991.5240684471494;1006.3056491021172;1014.497823577334;1103.4979084516526;13563;14013.789793153112;14317.140971541741;14423.567292981228;14442.783358249635;365188;369871;369889;370792;371862;51323.23015154776;51990;52088.21867671988;52197;52258.73870630623;1301.2517802263735;1306.73865226955;1320.8679132086716;1338.4913732781674;1343.1403901503045;26468.735312646626;26473.602895956963;26541.33646658849;26673.33316667021;26876.731232687423;41395.793021035905;46008.53991460043;47753;47817.76091119522;47893.76053119712;265903;271174;272957;272961;273885;41739.37390939078;41858.790706046275;42285.57714422939;42350.576494234665;42427;44637.107257855314;45295.54704453045;45362.319565205886;46260.7686961563;46274.53725462702;38264;38359;38500.80749596346;38555.61444385633;39327.80336098416;37158.8142059288;38052.42921356126;38451;38775.61224387833;39029.80485097556;35164.82417587896;37812.81093594514;37858.81070594629;38357.61642383652;40396.596034040456;31143.844280778452;31582.262345724524;31890.36219275555;31958.201044974063;31993.520097199027;25045;25489;25502.617460737732;25674.17271389756;25808.870955645853 16:20:58 INFO - PROCESS | 21119 | |2;jquery.html;360.46291026370716;361.20534823388545;365.16377495535215;367.9668829805317;372.296359879827;15898.125603091825;16241.83758162418;16755.246013929373;16759.66480670386;16780.244888979996;14461.855381446183;14632.634184145376;14794.704105917875;15276.694466110672;15313.464028758995;5019.623528235379;5044.772985215674;5221.347331583552;5229.843104706865;5241.161414173733;5136.203888397305;5147.27938088666;5190.818321358754;5218.425973142951;5242.711650859202;5102.6428150029515;5136.768845401947;5147.794088236485;5150.510701483353;5151.974240128813;4983.750812459362;5167.173252279636;5184.688918664854;5191.922121168185;5192;4548.385967894358;4580.106879158566;4606.286025665993;4609.746463944482;4621.907561848744;19799.30702425408;19852.602947941105;20315.492112697153;20346.18615255388;20862.687059693966;1970.2414570390504;1996.9700454493195;2027.0878104852727;2186.3768825884545;2448.5470188015274;12644.241345519298;13177.670558236025;13309.13490623116;13479.595612131652;13606.319684015858;1799.7660304160506;1829.0945981739123;1845.9538511537185;1875.831175194239;1894.479018269973;57276.71361643207;57348.139777903365;57644.423555763904;57716.711416442646;58956;9038.418276801533;9054.592543335566;9447.433154010781;9565.234781217487;9567;13108.672283192805;13217.73564528704;13219.933900330629;13223.93388033073;13306.268155251553;6860.5540639858245;7007.1591409030225;7009.053777740041;7059.399951004119;7098.787036388917;6648.368405001614;6714.597124172566;6752.864942701119;6760.391564759097;6760.797176084725;5765.221695071112;5803.709814509258;5863.2670916135485;5900.64596124234;5901.085331773582;1702.344597330012;1709;1718.707819670659;1733.4106403822761;1752.509297396731;925.9722208333761;947.3605316411395;955.7610597350663;1001.5493028137367;1056.498163372392;16292.348306067624;16717.55877539624;16794.6641067178;16816.663666726596;17394.304227830744;877.6182360673128;910.9544522773834;924.7549399409162;971.8347880860271;986.8618393424927;9848.21214302854;9888.851667224997;10030.498475076218;10079.249749067436;10174.694759157237;364589;369993;383160;384639;386684;57029;60841.99999999911;62081.37918620846;62975.6851215741;63161.684191578745;926.8053708721109;927.1192367251139;928.4754113925476;928.9907100928904;941.1717688434134;14834.332455039334;14994.625134371292;15035.473758418402;15257;15272.160031198391;29018.76096639233;30434.54348184823;30867.845660771553;31002.844985774926;31011;40758.59241407548;41044.79477602593;41227.1754564913;41579.16841663211;42080;7713.6143192841255;7736.419768517271;7950;7968.442209045311;8003.19968003195;11446.542138314708;11485.19603627738;11804.70488237801;11827.70430739204;12101.455434505644;10066.647667331608;10159.695209143738;10176.389416635026;10186;10252.282340236112;9956.950215248877;10121.139703125316;10168.186545076524;10199.439030853375;10324.535395907353;10279.331843430453;10365.689029329133;10534.262601617813;10553.261271710906;10685.412302323135;7722.845543089219;7755.185705501067;7768.53388796674;7769.961150194213;7833.255840695125;10561.683149505527;10579.735506612396;10638.468076595984;10670.946645266724;10713.196510261918 16:20:58 INFO - PROCESS | 21119 | |3;mootools.html;1436.9425222991078;1460.7516722157231;1633.2242184962145;1773.2020590734173;1826.105208447861;7474.588897610629;7569.318761311481;7710.190430004845;7719.343855772259;7766.37868970481;7793.727219547317;7901.604919754018;7902.130765615778;7904.960475197616;7944.046714394266;2221.1226565506613;2235.8099561537288;2245.6856040154394;2247.910083596654;2248.1119957616734;2226.7105276314137;2241.1147596699293;2288.1991303043883;2290.3014580552926;2293.7728315351205;2163.7836216378314;2177.6624623183434;2178.1396348442354;2207.183342163402;2254.233560589399;2238.485148415857;2270.28486026901;2285.131649973008;2285.1544928376607;2287.4510117571745;2283.2465286455595;2303.49323148907;2303.6429353450244;2304.3778179891337;2305.3890718959487;7845.725399611043;7885.763427097197;7906.169852165497;7930.9603451982375;7948.642311095957;4340.7178533395545;4464.0402313502755;4467.910641787146;4477.597016268519;4478.126765280772;2905.2155917902046;2979.970200297991;2994.82031078136;3007.3985202959498;3011.864466099031;911.7036962987056;941.7598512379368;942.4345392764355;947.8151760406723;959.7829014975429;10018.248631352677;10122.190224782;10132.34139780912;10142.492875356204;10175.338602990782;3938.330483817758;3979.064919743858;4039.111395492987;4045.7370270932297;4139.048018955657;8195.467294625949;8209.425340226127;8243.175682431709;8243.340532757366;8269.917300827035;4389.100234451929;4389.473263208435;4402.229609818269;4403.031333106712;4411.404460397805;3900.4929359183693;3962.7622342659533;3989.0027493126718;4011.659008984269;4013.1973605279063;3378.6621337866018;3395.7792743471596;3407.4377727674655;3425.383430982447;3451.11996440911;1009.8030883977701;1014.6905193915926;1017.0287375556326;1027.3989716016065;1034.9586016559404;997.5211898288862;1206.7224538356227;1212.1938910624335;1248.950041998328;1257.415301884628;6963.059986901805;7043.436525077981;7060.293970603001;7081.185663648644;7124.50128491011;471.62505807882815;484.9830255941025;486.6885193476178;488.577380565808;492.4902725678905;5556.249906262684;5594.804181853616;5595.608307418523;5628.915566266509;5938.732757025944;9189.172974432333;9484.241260699127;9496.620135194653;9512.762180945532;9517;9128.041555636695;9247.76880577991;9289.72130836076;9367.063293670579;9426.905730942603;6762.526623136431;6867.965660171667;6885.862282754317;6890;6899.448044156456;7137.1435427749;7302.488825782144;7311.634418278958;7316.341529262392;7461.179270280156;8273.958630206811;8316.085230624747;8363.038250601016;8432.114627964222;8505.192006759347;8454.66181352739;8563.186497282748;8595.828083438171;8641.56792160402;8649.783755406164;6176.4441200292185;6985.266547012694;7337.229590892879;7414.740484083031;7450.403967682464;6987.1266091738535;7037.929620703728;7038.753643622448;7044.401225895856;7080.362767350757;5969.820905372846;6211.13044173807;6251.6561589113035;6257.2491301044165;6297.023961286008;6175.814725558241;6239.814435257286;6255.374462553708;6256.530760192913;6314.968425157845;6640.468762499085;6692.732290708317;6722.932770672231;6809.080774095433;6881.862362752817;6476.708548115252;6542.901856472062;6543.934560654523;6546.476281897532;6559.770408035887;6287.717052732546;6581.276059633341;6586.275509693834;6592.143021407186;6617.636030018202 16:20:58 INFO - PROCESS | 21119 | |4;prototype.html;333.67300045954966;337.56622739779374;338.5717067909097;339.37385523708747;341.5679165855192;5726;5768.163616275638;5792.333881603617;5804.506616937555;5807;5720.914186287209;5727.599068065237;5743.138529220619;5744.741486633101;5746.798862039829;4282.186384586918;4397.384366188736;4398.780060996937;4405.8237670493145;4474.955250447487;4403.471583409995;4418.955810441887;4435.179491794029;4440.578145076212;4443.355713421552;4381.320895261224;4398.626116780078;4408.955910440903;4417.359482874981;4418;4399.42807435033;4414.536473670267;4430.844920427802;4432.556744325574;4434.5343738907595;3836.482074919878;3859.1509867829313;3866.149447121629;3873.0511024798825;3873.283442563135;5568;5574.358948720908;5579.69311687857;5586.804461843816;5595.132754423071;4868.150102960888;5083.720395378253;5086.898262034739;5091.719955402488;5093.465186155438;6041.275046994345;6071.908921366183;6073.392660733935;6074.696265186767;6089.756409743605;4172.812223449952;4173.749575025508;4182.790860456965;4192.937105943413;4193.203291374629;6325.905111423286;6391.968040159816;6398.680065996681;6401.263854656681;6426.132472116251;4241.045764702979;4267.210566045292;4269.274223382033;4271.337942618899;4279;4346.565343465628;4347.500037495665;4348.34774783785;4349.543297953669;4356.6514678825615;4870.586000190024;5036;5057.620678449131;5094.439611642716;5095.49682843562;6078.300995385499;6082.2093127893095;6090.665013424215;6091.360407157184;6111.205543279434;1526.5496678479876;1553.5882991007393;1553.906765594068;1554.4637100200414;1557.314781496138;985.4186030242048;1016.5425558498705;1021.8824835143905;1023.3143793658267;1028.573142146001;5376.892462150735;5377.4084850666395;5384.0847056000575;5396.541293989976;5399.919001214985;6283.183186185858;6300.527460440485;6328.272248691459;6337.936620633827;6341.587796793194;5190.766415511311;5324.9733751331;5374.48942350476;5394.676319420847;5413.10683737179;3430.451127819537;3454.9136271592906;3460.1522626956557;3461.1174150591437;3469.705075068597;6064.787732429344;6112.969435152796;6114.144019837227;6114.991026480581;6119.908201376983;6547.476201903836;6552.246491653425;6561.507886908405;6583.308752581006;6591.6374599397495;6080.8783824324155;6111.266648002179;6117.877642447126;6132.50939924805;6136.079588061826;6235.812925612239;6290.685465726787;6314.4632706218645;6389.169407976933;6399.520035997226;6182.845428864314;6241.531885108726;6252.687365631791;6258.186435763321;6260.186175797117;6093;6095.05526643371;6097.390260973867;6100.054491553817;6115.4190351916495;4426.402435671143;4464.15181115587;4468.352088947149;4471.575200355961;4475.239209334486;6201.534884883742;6250.3749625037135;6307.558470906993;6322.083297921868;6415.133956915756;6142.723577438936;6145.84635384119;6146.354632763674;6150.323464419;6152.230971128609;6098.054801505774;6142.600730952474;6148.938510614837;6152.354002829638;6159.106929495288;5931.703414829155;5938;5990.281166260077;5998.190244316961;5999.040153575582;6051.818445446644;6122.867269555147;6145.66198859067;6192.969035154795;6206.72069756853;5946.672932988554;5975;5981.880362392815;5988.37122102173;5989.101634754822 16:20:58 INFO - PROCESS | 21119 | |5;yui.html;561.9943800561995;566.1875209074977;570.6718636783851;586.9236999190105;620.0606081786095;10700.39494075889;11277.830832537507;11376.488058037397;11391.202615816896;11459.942700286487;10793.83809242862;10895.618653347134;12647.494100236003;12982.675433114153;13003.089783715144;704.89426586012;859.5057841740993;863.5552690364436;866.4844417571551;876.2114097312437;856.6787454704486;857.4426622695266;858.4291446188278;858.7853036740814;859.3468963587687;893.0399820193286;895.7133717210495;898.5058217980085;899.6266549381999;903.0427746588637;888.5601627194548;890.8218356328728;894.7941973346102;895.7984453497976;898.191627535217;663.2207156590986;663.7942237906265;667.0994157886814;669.4744625469032;670.6378555579981;11762.941185294105;11828.172027958046;11852.466639001266;11866.52533898643;11877.643670689893;11640.476178571984;11792.941035294854;12059.517619295219;12075.698107547294;12267.509299628004;9418.481983490905;9477.597769271519;9603.279754018477;9700.563474643657;9787.070228328297;2917.3435976905025;2927.487689654302;2935.5890175375466;2937.456570534458;2985.5372417275357;42757.358639620434;42919.92700182489;43109.35335969963;43312.78343608294;43488.130237395395;8630.352723545759;9191.770205744777;9236.584353703965;9245.72262832116;9266.258699304039;16564;16694.4156954506;16695.081770502497;16767.329306827833;16892.662146756997;5806.593538452352;5861.648301101947;6097.115918191837;6308.274548426898;6649.168853893263;3700.20445604191;3726.832292546788;3733.5706393765063;3800.1449673823167;3833.3100041992707;2656.4288677934337;2682.262377846108;2684.167907948542;2684.691260505028;2687.6774787025684;514.2851436503263;563.3690266901096;571.5741772379604;579.7275280618103;583.5069366385397;403.13125215161375;422.06091446531224;443.6406510726322;497.4031162604816;517.0666946162138;6812.897806532906;6888.345607167411;7103.2541583133025;7115.430765538848;7259.782206533812;516.4964159944025;520.4873199898097;535.3094508084528;537.1942086869695;543.1282791120274;3399.9320013600086;3413.385590593717;3489.7382696297877;3495.545579074704;3506.386382383073;49142;51413;51779;51805.222921656205;51942;44778.55221447744;45245;45248.773756131006;46580.301295480596;46611.30083048757;39344.60655393467;40366.19267614631;40474.19051618951;41387;41590.168196635896;6485.708143133665;6532.640704761284;6692.732290708317;6742.08981787472;6984.3364880336285;30989.53515697221;31113.844430777703;31439.842800786766;32071.518927215642;32233.67766322307;37028.25943481169;40645;41089.79455102825;41302.1739565213;41556;8412.453190542596;8500.489970601784;8503.702370417006;8506.404551681571;8511.702090426805;9051.59267832936;9085.454872707658;9111.453312801254;9316.860247096434;9390.765230869283;8510.957445212734;8565.100664430394;8610.569471526524;8653.740387788124;8684.348673849612;8369.916300836914;8447.619857106321;8478.406511544134;8504;8597;8686.478811271343;8748.47509149453;8812.118788121137;8902.643894244158;8917.152870477294;7051.929480705127;7197.424206063607;7308.342249197598;7450.031495905498;7643.273888980538;7431.331180193809;7623.923760762322;7706.845863082819;7758.806029849299;7851.764447066597 16:20:58 INFO - PROCESS | 21119 | __end_tp_report 16:20:58 INFO - PROCESS | 21119 | __start_cc_report 16:20:58 INFO - PROCESS | 21119 | _x_x_mozilla_cycle_collect,332 16:20:58 INFO - PROCESS | 21119 | __end_cc_report 16:20:58 INFO - PROCESS | 21119 | __startTimestamp1467328858795__endTimestamp 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | ------- Summary: start ------- 16:20:58 INFO - PROCESS | 21119 | Number of tests: 6 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#0] dojo.html Cycles:165 Average:4714.58 Median:4830.57 stddev:460.23 (9.5%) stddev-sans-first:436.35 16:20:58 INFO - PROCESS | 21119 | Values: 2796.5 2840.4 2873.1 2907.5 2919.5 4718.6 4721.3 4722.0 4724.3 4770.5 4733.8 4747.3 4754.2 4768.5 4781.6 4354.5 4372.6 4382.7 4390.6 4420.9 4223.4 4371.2 4416.4 4428.7 4447.9 4422.8 4441.1 4492.6 4511.1 4519.5 4433.0 4447.4 4451.8 4456.0 4496.0 3940.7 4356.5 4406.3 4407.3 4433.3 4642.9 4656.7 4669.3 4682.0 4696.2 5083.8 5085.5 5102.0 5111.2 5163.0 4885.1 4998.5 5044.8 5066.9 5072.8 4692.0 4694.8 4699.6 4700.6 4716.7 4932.7 5172.2 5180.6 5184.8 5199.8 5015.4 5020.6 5032.3 5066.9 5094.4 5079.2 5101.0 5120.6 5125.2 5137.8 5043.4 5051.3 5055.0 5065.1 5102.7 4999.0 5013.2 5020.4 5025.3 5053.2 4277.3 4287.0 4306.6 4314.2 4320.9 3774.9 3803.6 3812.4 3831.8 3854.7 4370.6 4480.1 4485.3 4513.5 4555.3 5087.6 5136.8 5146.2 5146.4 5193.0 4378.1 4452.9 4495.7 4513.8 4517.1 4514.8 4528.4 4531.6 4533.3 4544.7 4802.0 4841.2 4842.4 4875.9 4900.1 5105.8 5110.3 5115.4 5139.0 5168.8 4877.0 4885.4 4925.0 4935.1 4959.0 4796.9 4975.8 5037.6 5041.8 5089.9 5103.8 5112.7 5124.1 5126.9 5132.8 4982.2 4983.1 4984.9 4994.8 5003.4 4957.5 4966.9 5018.3 5032.7 5041.2 4780.5 4817.5 4825.3 4830.6 4840.1 4781.2 4972.0 4985.0 4985.7 4993.7 4781.4 4849.3 4853.7 4872.9 4877.9 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#1] ext.html Cycles:180 Average:43580.25 Median:367529.50 stddev:73563.63 (20.0%) stddev-sans-first:73699.05 16:20:58 INFO - PROCESS | 21119 | Values: 551.1 551.7 561.7 562.3 567.7 33969.7 34742.3 34773.7 34841.3 34964.5 34603.3 34992.7 35016.0 35056.8 35217.3 7816.6 8031.8 8035.4 8061.4 8062.5 7792.1 8160.8 8166.1 8167.1 8177.2 8097.4 8188.6 8194.8 8217.0 8242.1 8051.8 8097.1 8146.8 8152.1 8160.3 8088.2 8268.9 8271.7 8271.8 8290.7 31743.2 31872.8 31984.4 32230.8 32253.4 2304.8 2704.3 2903.5 3013.1 3088.3 26490.9 26803.2 26856.3 27005.5 27314.0 3087.1 3097.6 3121.3 3167.8 3197.0 151254.0 152120.0 152143.0 152668.2 153061.0 55297.0 55557.4 55595.4 55936.7 56069.7 59070.4 59142.4 59277.0 65769.3 67406.7 13294.4 13367.0 13625.1 13641.2 13663.8 10106.4 10115.3 10178.2 10187.7 10288.8 20628.5 21186.5 21345.3 21360.6 21401.9 7339.0 7424.2 7523.4 7736.7 7787.8 749.3 942.3 945.8 975.5 1119.5 27400.8 27591.9 27818.3 28276.9 28288.6 971.2 991.5 1006.3 1014.5 1103.5 13563.0 14013.8 14317.1 14423.6 14442.8 365188.0 369871.0 369889.0 370792.0 371862.0 51323.2 51990.0 52088.2 52197.0 52258.7 1301.3 1306.7 1320.9 1338.5 1343.1 26468.7 26473.6 26541.3 26673.3 26876.7 41395.8 46008.5 47753.0 47817.8 47893.8 265903.0 271174.0 272957.0 272961.0 273885.0 41739.4 41858.8 42285.6 42350.6 42427.0 44637.1 45295.5 45362.3 46260.8 46274.5 38264.0 38359.0 38500.8 38555.6 39327.8 37158.8 38052.4 38451.0 38775.6 39029.8 35164.8 37812.8 37858.8 38357.6 40396.6 31143.8 31582.3 31890.4 31958.2 31993.5 25045.0 25489.0 25502.6 25674.2 25808.9 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#2] jquery.html Cycles:180 Average:22789.96 Median:191766.15 stddev:61807.85 (32.2%) stddev-sans-first:61958.29 16:20:58 INFO - PROCESS | 21119 | Values: 360.5 361.2 365.2 368.0 372.3 15898.1 16241.8 16755.2 16759.7 16780.2 14461.9 14632.6 14794.7 15276.7 15313.5 5019.6 5044.8 5221.3 5229.8 5241.2 5136.2 5147.3 5190.8 5218.4 5242.7 5102.6 5136.8 5147.8 5150.5 5152.0 4983.8 5167.2 5184.7 5191.9 5192.0 4548.4 4580.1 4606.3 4609.7 4621.9 19799.3 19852.6 20315.5 20346.2 20862.7 1970.2 1997.0 2027.1 2186.4 2448.5 12644.2 13177.7 13309.1 13479.6 13606.3 1799.8 1829.1 1846.0 1875.8 1894.5 57276.7 57348.1 57644.4 57716.7 58956.0 9038.4 9054.6 9447.4 9565.2 9567.0 13108.7 13217.7 13219.9 13223.9 13306.3 6860.6 7007.2 7009.1 7059.4 7098.8 6648.4 6714.6 6752.9 6760.4 6760.8 5765.2 5803.7 5863.3 5900.6 5901.1 1702.3 1709.0 1718.7 1733.4 1752.5 926.0 947.4 955.8 1001.5 1056.5 16292.3 16717.6 16794.7 16816.7 17394.3 877.6 911.0 924.8 971.8 986.9 9848.2 9888.9 10030.5 10079.2 10174.7 364589.0 369993.0 383160.0 384639.0 386684.0 57029.0 60842.0 62081.4 62975.7 63161.7 926.8 927.1 928.5 929.0 941.2 14834.3 14994.6 15035.5 15257.0 15272.2 29018.8 30434.5 30867.8 31002.8 31011.0 40758.6 41044.8 41227.2 41579.2 42080.0 7713.6 7736.4 7950.0 7968.4 8003.2 11446.5 11485.2 11804.7 11827.7 12101.5 10066.6 10159.7 10176.4 10186.0 10252.3 9957.0 10121.1 10168.2 10199.4 10324.5 10279.3 10365.7 10534.3 10553.3 10685.4 7722.8 7755.2 7768.5 7770.0 7833.3 10561.7 10579.7 10638.5 10670.9 10713.2 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#3] mootools.html Cycles:180 Average:5292.04 Median:6255.95 stddev:2788.45 (44.6%) stddev-sans-first:2781.21 16:20:58 INFO - PROCESS | 21119 | Values: 1436.9 1460.8 1633.2 1773.2 1826.1 7474.6 7569.3 7710.2 7719.3 7766.4 7793.7 7901.6 7902.1 7905.0 7944.0 2221.1 2235.8 2245.7 2247.9 2248.1 2226.7 2241.1 2288.2 2290.3 2293.8 2163.8 2177.7 2178.1 2207.2 2254.2 2238.5 2270.3 2285.1 2285.2 2287.5 2283.2 2303.5 2303.6 2304.4 2305.4 7845.7 7885.8 7906.2 7931.0 7948.6 4340.7 4464.0 4467.9 4477.6 4478.1 2905.2 2980.0 2994.8 3007.4 3011.9 911.7 941.8 942.4 947.8 959.8 10018.2 10122.2 10132.3 10142.5 10175.3 3938.3 3979.1 4039.1 4045.7 4139.0 8195.5 8209.4 8243.2 8243.3 8269.9 4389.1 4389.5 4402.2 4403.0 4411.4 3900.5 3962.8 3989.0 4011.7 4013.2 3378.7 3395.8 3407.4 3425.4 3451.1 1009.8 1014.7 1017.0 1027.4 1035.0 997.5 1206.7 1212.2 1249.0 1257.4 6963.1 7043.4 7060.3 7081.2 7124.5 471.6 485.0 486.7 488.6 492.5 5556.2 5594.8 5595.6 5628.9 5938.7 9189.2 9484.2 9496.6 9512.8 9517.0 9128.0 9247.8 9289.7 9367.1 9426.9 6762.5 6868.0 6885.9 6890.0 6899.4 7137.1 7302.5 7311.6 7316.3 7461.2 8274.0 8316.1 8363.0 8432.1 8505.2 8454.7 8563.2 8595.8 8641.6 8649.8 6176.4 6985.3 7337.2 7414.7 7450.4 6987.1 7037.9 7038.8 7044.4 7080.4 5969.8 6211.1 6251.7 6257.2 6297.0 6175.8 6239.8 6255.4 6256.5 6315.0 6640.5 6692.7 6722.9 6809.1 6881.9 6476.7 6542.9 6543.9 6546.5 6559.8 6287.7 6581.3 6586.3 6592.1 6617.6 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#4] prototype.html Cycles:180 Average:5056.27 Median:5726.80 stddev:1505.42 (26.3%) stddev-sans-first:1467.32 16:20:58 INFO - PROCESS | 21119 | Values: 333.7 337.6 338.6 339.4 341.6 5726.0 5768.2 5792.3 5804.5 5807.0 5720.9 5727.6 5743.1 5744.7 5746.8 4282.2 4397.4 4398.8 4405.8 4475.0 4403.5 4419.0 4435.2 4440.6 4443.4 4381.3 4398.6 4409.0 4417.4 4418.0 4399.4 4414.5 4430.8 4432.6 4434.5 3836.5 3859.2 3866.1 3873.1 3873.3 5568.0 5574.4 5579.7 5586.8 5595.1 4868.2 5083.7 5086.9 5091.7 5093.5 6041.3 6071.9 6073.4 6074.7 6089.8 4172.8 4173.7 4182.8 4192.9 4193.2 6325.9 6392.0 6398.7 6401.3 6426.1 4241.0 4267.2 4269.3 4271.3 4279.0 4346.6 4347.5 4348.3 4349.5 4356.7 4870.6 5036.0 5057.6 5094.4 5095.5 6078.3 6082.2 6090.7 6091.4 6111.2 1526.5 1553.6 1553.9 1554.5 1557.3 985.4 1016.5 1021.9 1023.3 1028.6 5376.9 5377.4 5384.1 5396.5 5399.9 6283.2 6300.5 6328.3 6337.9 6341.6 5190.8 5325.0 5374.5 5394.7 5413.1 3430.5 3454.9 3460.2 3461.1 3469.7 6064.8 6113.0 6114.1 6115.0 6119.9 6547.5 6552.2 6561.5 6583.3 6591.6 6080.9 6111.3 6117.9 6132.5 6136.1 6235.8 6290.7 6314.5 6389.2 6399.5 6182.8 6241.5 6252.7 6258.2 6260.2 6093.0 6095.1 6097.4 6100.1 6115.4 4426.4 4464.2 4468.4 4471.6 4475.2 6201.5 6250.4 6307.6 6322.1 6415.1 6142.7 6145.8 6146.4 6150.3 6152.2 6098.1 6142.6 6148.9 6152.4 6159.1 5931.7 5938.0 5990.3 5998.2 5999.0 6051.8 6122.9 6145.7 6193.0 6206.7 5946.7 5975.0 5981.9 5988.4 5989.1 16:20:58 INFO - PROCESS | 21119 | 16:20:58 INFO - PROCESS | 21119 | [#5] yui.html Cycles:180 Average:12018.54 Median:575.65 stddev:14311.03 (2486.1%) stddev-sans-first:14325.31 16:20:58 INFO - PROCESS | 21119 | Values: 562.0 566.2 570.7 586.9 620.1 10700.4 11277.8 11376.5 11391.2 11459.9 10793.8 10895.6 12647.5 12982.7 13003.1 704.9 859.5 863.6 866.5 876.2 856.7 857.4 858.4 858.8 859.3 893.0 895.7 898.5 899.6 903.0 888.6 890.8 894.8 895.8 898.2 663.2 663.8 667.1 669.5 670.6 11762.9 11828.2 11852.5 11866.5 11877.6 11640.5 11792.9 12059.5 12075.7 12267.5 9418.5 9477.6 9603.3 9700.6 9787.1 2917.3 2927.5 2935.6 2937.5 2985.5 42757.4 42919.9 43109.4 43312.8 43488.1 8630.4 9191.8 9236.6 9245.7 9266.3 16564.0 16694.4 16695.1 16767.3 16892.7 5806.6 5861.6 6097.1 6308.3 6649.2 3700.2 3726.8 3733.6 3800.1 3833.3 2656.4 2682.3 2684.2 2684.7 2687.7 514.3 563.4 571.6 579.7 583.5 403.1 422.1 443.6 497.4 517.1 6812.9 6888.3 7103.3 7115.4 7259.8 516.5 520.5 535.3 537.2 543.1 3399.9 3413.4 3489.7 3495.5 3506.4 49142.0 51413.0 51779.0 51805.2 51942.0 44778.6 45245.0 45248.8 46580.3 46611.3 39344.6 40366.2 40474.2 41387.0 41590.2 6485.7 6532.6 6692.7 6742.1 6984.3 30989.5 31113.8 31439.8 32071.5 32233.7 37028.3 40645.0 41089.8 41302.2 41556.0 8412.5 8500.5 8503.7 8506.4 8511.7 9051.6 9085.5 9111.5 9316.9 9390.8 8511.0 8565.1 8610.6 8653.7 8684.3 8369.9 8447.6 8478.4 8504.0 8597.0 8686.5 8748.5 8812.1 8902.6 8917.2 7051.9 7197.4 7308.3 7450.0 7643.3 7431.3 7623.9 7706.8 7758.8 7851.8 16:20:58 INFO - PROCESS | 21119 | -------- Summary: end -------- 16:20:58 INFO - PROCESS | 21119 | 16:20:59 INFO - TEST-INFO | 21119: exit 0 16:20:59 INFO - TEST-OK | dromaeo_css | took 1112984ms 16:20:59 INFO - TEST-START | kraken 16:20:59 INFO - operating with platform_type : linux_ 16:20:59 INFO - Initialising browser for kraken test... 16:20:59 INFO - TEST-INFO | started process 21359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LKoWJ/profile http://localhost:53497/getInfo.html) 16:21:03 INFO - PROCESS | 21359 | 16:21:03 INFO - PROCESS | 21359 | (process:21434): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:21:04 INFO - PROCESS | 21359 | __metrics Screen width/height:1600/1200 16:21:04 INFO - PROCESS | 21359 | colorDepth:24 16:21:04 INFO - PROCESS | 21359 | Browser inner width/height: 1024/697 16:21:04 INFO - PROCESS | 21359 | __metrics 16:21:04 ERROR - PROCESS | 21359 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 16:21:05 INFO - TEST-INFO | 21359: exit 0 16:21:05 INFO - Browser initialized. 16:21:05 INFO - Running cycle 1/1 for kraken test... 16:21:05 INFO - TEST-INFO | started process 21484 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5LKoWJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:07 INFO - PROCESS | 21484 | 16:21:07 INFO - PROCESS | 21484 | (process:21550): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:21:17 INFO - PROCESS | 21484 | RSS: Main: 149725184 16:21:17 INFO - PROCESS | 21484 | 16:21:20 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:20 INFO - PROCESS | 21484 | RSS: Main: 153628672 16:21:20 INFO - PROCESS | 21484 | 16:21:23 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-dft) 16:21:24 INFO - PROCESS | 21484 | RSS: Main: 153833472 16:21:24 INFO - PROCESS | 21484 | 16:21:27 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-fft) 16:21:27 INFO - PROCESS | 21484 | RSS: Main: 154394624 16:21:27 INFO - PROCESS | 21484 | 16:21:29 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-oscillator) 16:21:30 INFO - PROCESS | 21484 | RSS: Main: 154558464 16:21:30 INFO - PROCESS | 21484 | 16:21:32 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:33 INFO - PROCESS | 21484 | RSS: Main: 154349568 16:21:33 INFO - PROCESS | 21484 | 16:21:36 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:36 INFO - PROCESS | 21484 | RSS: Main: 154820608 16:21:36 INFO - PROCESS | 21484 | 16:21:40 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:41 INFO - PROCESS | 21484 | RSS: Main: 154669056 16:21:41 INFO - PROCESS | 21484 | 16:21:43 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53497/tests/kraken/driver.html?testName=json-parse-financial) 16:21:44 INFO - PROCESS | 21484 | RSS: Main: 154689536 16:21:44 INFO - PROCESS | 21484 | 16:21:46 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53497/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:46 INFO - PROCESS | 21484 | RSS: Main: 154730496 16:21:46 INFO - PROCESS | 21484 | 16:21:48 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:49 INFO - PROCESS | 21484 | RSS: Main: 154898432 16:21:49 INFO - PROCESS | 21484 | 16:21:51 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:52 INFO - PROCESS | 21484 | RSS: Main: 155234304 16:21:52 INFO - PROCESS | 21484 | 16:21:54 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:55 INFO - PROCESS | 21484 | RSS: Main: 155389952 16:21:55 INFO - PROCESS | 21484 | 16:21:57 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:58 INFO - PROCESS | 21484 | RSS: Main: 155324416 16:21:58 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53497/tests/kraken/driver.html?testName=ai-astar) 16:22:00 INFO - PROCESS | 21484 | RSS: Main: 156618752 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | __start_tp_report 16:22:00 INFO - PROCESS | 21484 | _x_x_mozilla_page_load 16:22:00 INFO - PROCESS | 21484 | _x_x_mozilla_page_load_details 16:22:00 INFO - PROCESS | 21484 | |i|pagename|runs| 16:22:00 INFO - PROCESS | 21484 | |0;ai-astar;112;113;106;113;114;113;103;113;114;112 16:22:00 INFO - PROCESS | 21484 | |1;audio-beat-detection;159;139;161;167;136;158;159;140;185;138 16:22:00 INFO - PROCESS | 21484 | |2;audio-dft;161;163;162;162;163;163;163;161;162;163 16:22:00 INFO - PROCESS | 21484 | |3;audio-fft;83;77;77;158;78;78;77;79;160;77 16:22:00 INFO - PROCESS | 21484 | |4;audio-oscillator;95;94;143;95;95;94;143;95;95;134 16:22:00 INFO - PROCESS | 21484 | |5;imaging-gaussian-blur;117;118;118;118;117;117;117;120;118;118 16:22:00 INFO - PROCESS | 21484 | |6;imaging-darkroom;147;147;147;146;148;148;148;147;148;148 16:22:00 INFO - PROCESS | 21484 | |7;imaging-desaturate;96;95;96;95;96;96;95;96;96;95 16:22:00 INFO - PROCESS | 21484 | |8;json-parse-financial;68;68;70;69;70;67;67;69;78;70 16:22:00 INFO - PROCESS | 21484 | |9;json-stringify-tinderbox;64;64;64;64;66;65;65;65;65;65 16:22:00 INFO - PROCESS | 21484 | |10;stanford-crypto-aes;80;79;81;82;82;80;81;83;81;82 16:22:00 INFO - PROCESS | 21484 | |11;stanford-crypto-ccm;121;118;130;132;122;121;124;119;126;132 16:22:00 INFO - PROCESS | 21484 | |12;stanford-crypto-pbkdf2;138;145;150;147;143;130;148;145;155;130 16:22:00 INFO - PROCESS | 21484 | |13;stanford-crypto-sha256-iterative;69;70;72;67;67;66;68;69;68;67 16:22:00 INFO - PROCESS | 21484 | __end_tp_report 16:22:00 INFO - PROCESS | 21484 | __start_cc_report 16:22:00 INFO - PROCESS | 21484 | _x_x_mozilla_cycle_collect,919 16:22:00 INFO - PROCESS | 21484 | __end_cc_report 16:22:00 INFO - PROCESS | 21484 | __startTimestamp1467328920286__endTimestamp 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | ------- Summary: start ------- 16:22:00 INFO - PROCESS | 21484 | Number of tests: 14 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#0] ai-astar Cycles:10 Average:111.30 Median:113.00 stddev:3.71 (3.3%) stddev-sans-first:3.93 16:22:00 INFO - PROCESS | 21484 | Values: 112.0 113.0 106.0 113.0 114.0 113.0 103.0 113.0 114.0 112.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#1] audio-beat-detection Cycles:10 Average:154.20 Median:159.00 stddev:15.80 (9.9%) stddev-sans-first:16.66 16:22:00 INFO - PROCESS | 21484 | Values: 159.0 139.0 161.0 167.0 136.0 158.0 159.0 140.0 185.0 138.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#2] audio-dft Cycles:10 Average:162.30 Median:163.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 16:22:00 INFO - PROCESS | 21484 | Values: 161.0 163.0 162.0 162.0 163.0 163.0 163.0 161.0 162.0 163.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#3] audio-fft Cycles:10 Average:94.40 Median:77.50 stddev:34.10 (44.0%) stddev-sans-first:35.92 16:22:00 INFO - PROCESS | 21484 | Values: 83.0 77.0 77.0 158.0 78.0 78.0 77.0 79.0 160.0 77.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#4] audio-oscillator Cycles:10 Average:108.30 Median:95.00 stddev:22.02 (23.2%) stddev-sans-first:22.82 16:22:00 INFO - PROCESS | 21484 | Values: 95.0 94.0 143.0 95.0 95.0 94.0 143.0 95.0 95.0 134.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 16:22:00 INFO - PROCESS | 21484 | Values: 117.0 118.0 118.0 118.0 117.0 117.0 117.0 120.0 118.0 118.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 16:22:00 INFO - PROCESS | 21484 | Values: 147.0 147.0 147.0 146.0 148.0 148.0 148.0 147.0 148.0 148.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:22:00 INFO - PROCESS | 21484 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#8] json-parse-financial Cycles:10 Average:69.60 Median:69.50 stddev:3.17 (4.6%) stddev-sans-first:3.31 16:22:00 INFO - PROCESS | 21484 | Values: 68.0 68.0 70.0 69.0 70.0 67.0 67.0 69.0 78.0 70.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 16:22:00 INFO - PROCESS | 21484 | Values: 64.0 64.0 64.0 64.0 66.0 65.0 65.0 65.0 65.0 65.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.50 stddev:1.20 (1.5%) stddev-sans-first:1.20 16:22:00 INFO - PROCESS | 21484 | Values: 80.0 79.0 81.0 82.0 82.0 80.0 81.0 83.0 81.0 82.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:125.00 stddev:5.25 (4.2%) stddev-sans-first:5.42 16:22:00 INFO - PROCESS | 21484 | Values: 121.0 118.0 130.0 132.0 122.0 121.0 124.0 119.0 126.0 132.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.10 Median:146.00 stddev:8.20 (5.6%) stddev-sans-first:8.49 16:22:00 INFO - PROCESS | 21484 | Values: 138.0 145.0 150.0 147.0 143.0 130.0 148.0 145.0 155.0 130.0 16:22:00 INFO - PROCESS | 21484 | 16:22:00 INFO - PROCESS | 21484 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.77 (2.6%) stddev-sans-first:1.86 16:22:00 INFO - PROCESS | 21484 | Values: 69.0 70.0 72.0 67.0 67.0 66.0 68.0 69.0 68.0 67.0 16:22:00 INFO - PROCESS | 21484 | -------- Summary: end -------- 16:22:00 INFO - PROCESS | 21484 | 16:22:01 INFO - TEST-INFO | 21484: exit 0 16:22:01 INFO - TEST-OK | kraken | took 61704ms 16:22:01 INFO - SUITE-END | took 1174s 16:22:01 INFO - Completed test suite (00:19:35) 16:22:01 INFO - javascript benchmark 16:22:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2796.482650709619, 2840.403515261795, 2873.080614203453, 2907.462119507891, 2919.474494590973, 4718.598919091877, 4721.3390125382475, 4721.976390118049, 4724.31497432872, 4770.4513980892125, 4733.8343157989475, 4747.335373047776, 4754.16802059639, 4768.523147685239, 4781.641376896738, 4354.521002689694, 4372.5627437256335, 4382.693211475198, 4390.604845563898, 4420.889477763066, 4223.387608796723, 4371.235033869076, 4416.425864637593, 4428.734275943438, 4447.911041779163, 4422.756748378838, 4441.111777644468, 4492.573205545468, 4511.120331535352, 4519.502854685982, 4433.046894917576, 4447.355133505656, 4451.755153466558, 4456.041950980539, 4496.033352829141, 3940.704447166474, 4356.520782713899, 4406.317020861771, 4407.294832826765, 4433.2685106957315, 4642.930356044662, 4656.674032817704, 4669.252919532859, 4681.976590117061, 4696.178168820477, 5083.822066227701, 5085.491450854922, 5102.0, 5111.2333150027425, 5163.044836705184, 4885.120678277908, 4998.450170481244, 5044.772985215674, 5066.949330506684, 5072.797088116471, 4692.0, 4694.788734506956, 4699.553542413465, 4700.623950083985, 4716.740579268105, 4932.654714170046, 5172.224166374998, 5180.637355385163, 5184.792608295701, 5199.818006369759, 5015.448300686921, 5020.598352131894, 5032.3206367139965, 5066.898662026739, 5094.363204599425, 5079.187330027178, 5100.974495127501, 5120.564751996122, 5125.1799712045895, 5137.794488220504, 5043.394792624908, 5051.3433253676785, 5055.014272216956, 5065.062963351792, 5102.69383836971, 4999.0, 5013.24801279811, 5020.422651395063, 5025.321581586512, 5053.242013697901, 4277.315629499328, 4287.0, 4306.633936115402, 4314.22343978087, 4320.935185972213, 3774.867879624255, 3803.6386543278345, 3812.3519001769764, 3831.750936189139, 3854.6530812227033, 4370.584794444522, 4480.14877173336, 4485.349624304457, 4513.526079761577, 4555.2711566149255, 5087.64386492942, 5136.794528218829, 5146.202338637517, 5146.382434107931, 5193.039287731782, 4378.08060307339, 4452.933206001912, 4495.707778994421, 4513.774311284423, 4517.141743068806, 4514.751688657089, 4528.41130653013, 4531.637469002472, 4533.252013417815, 4544.704594201433, 4801.975990120027, 4841.2496063110975, 4842.4189097307135, 4875.902481950341, 4900.093482705711, 5105.795768169231, 5110.284560161506, 5115.386153661436, 5139.023585518739, 5168.793248270027, 4876.95123048765, 4885.364902562644, 4925.0, 4935.062338155739, 4959.0, 4796.9280460792415, 4975.776090076028, 5037.622178336566, 5041.823536176216, 5089.8727531812, 5103.821366252163, 5112.71880046601, 5124.052050370693, 5126.871828204325, 5132.820351287687, 4982.227754697883, 4983.127952608352, 4984.900301994012, 4994.750262486788, 5003.44962054181, 4957.529034741694, 4966.900661986668, 5018.322526458881, 5032.7483625819295, 5041.2438134280155, 4780.545848144421, 4817.470078291315, 4825.3485779420735, 4830.565249127595, 4840.12264564175, 4781.187198176249, 4971.975140124277, 4985.030982385279, 4985.725785081855, 4993.700377977333, 4781.354517140142, 4849.345338379415, 4853.708777473363, 4872.878178045577, 4877.926831097466], "unit": "score", "name": "dojo.html", "value": 4688.295773369256}, {"lowerIsBetter": false, "replicates": [551.0769461152569, 551.6827824001199, 561.7051048199695, 562.3364429972634, 567.662240966625, 33969.66030339696, 34742.30515389694, 34773.65226347742, 34841.3031739365, 34964.47553286699, 34603.30793384131, 34992.65007349926, 35016.0, 35056.824715876384, 35217.295654086905, 7816.609169541514, 8031.759047228563, 8035.357171426272, 8061.395395345343, 8062.475939063971, 7792.142864284952, 8160.795980100485, 8166.142555031725, 8167.060788009396, 8177.182281771835, 8097.392695547857, 8188.631511581993, 8194.795130121734, 8217.0, 8242.052164001158, 8051.798705032392, 8097.149799271051, 8146.75559733209, 8152.062512811014, 8160.26557609818, 8088.150744171895, 8268.917310826935, 8271.669133234664, 8271.834563308761, 8290.709825156091, 31743.20641983946, 31872.840635796903, 31984.360312793615, 32230.83884580562, 32253.35493290121, 2304.781045800646, 2704.310400847791, 2903.54994975777, 3013.126193403932, 3088.3051313454293, 26490.867545662342, 26803.19590412291, 26856.328591785168, 27005.459890802074, 27314.0, 3087.1356020314347, 3097.6282846058393, 3121.3133110715607, 3167.8099314041, 3196.9840150799328, 151254.0, 152120.0, 152143.0, 152668.236658816, 153061.0, 55297.0, 55557.44442555604, 55595.44404555984, 55936.720316398154, 56069.71965140148, 59070.40929590735, 59142.40857591455, 59277.0, 65769.34230657728, 67406.66296668584, 13294.401751921187, 13367.0, 13625.114367566077, 13641.181529108286, 13663.795043074362, 10106.39361638304, 10115.342502737298, 10178.18574514037, 10187.694369168938, 10288.845667314996, 20628.484287892923, 21186.470338241666, 21345.25291614786, 21360.57278854414, 21401.892990534947, 7339.0, 7424.220456852061, 7523.435742319348, 7736.6518506667335, 7787.805304867424, 749.2544917806817, 942.3403617467663, 945.8486642137225, 975.512243878061, 1119.5074167366333, 27400.775917594845, 27591.86204069007, 27818.3045423862, 28276.85861570679, 28288.575671364946, 971.2375785008876, 991.5240684471494, 1006.3056491021172, 1014.497823577334, 1103.4979084516526, 13563.0, 14013.789793153112, 14317.140971541741, 14423.567292981228, 14442.783358249635, 365188.0, 369871.0, 369889.0, 370792.0, 371862.0, 51323.23015154776, 51990.0, 52088.21867671988, 52197.0, 52258.73870630623, 1301.2517802263735, 1306.73865226955, 1320.8679132086716, 1338.4913732781674, 1343.1403901503045, 26468.735312646626, 26473.602895956963, 26541.33646658849, 26673.33316667021, 26876.731232687423, 41395.793021035905, 46008.53991460043, 47753.0, 47817.76091119522, 47893.76053119712, 265903.0, 271174.0, 272957.0, 272961.0, 273885.0, 41739.37390939078, 41858.790706046275, 42285.57714422939, 42350.576494234665, 42427.0, 44637.107257855314, 45295.54704453045, 45362.319565205886, 46260.7686961563, 46274.53725462702, 38264.0, 38359.0, 38500.80749596346, 38555.61444385633, 39327.80336098416, 37158.8142059288, 38052.42921356126, 38451.0, 38775.61224387833, 39029.80485097556, 35164.82417587896, 37812.81093594514, 37858.81070594629, 38357.61642383652, 40396.596034040456, 31143.844280778452, 31582.262345724524, 31890.36219275555, 31958.201044974063, 31993.520097199027, 25045.0, 25489.0, 25502.617460737732, 25674.17271389756, 25808.870955645853], "unit": "score", "name": "ext.html", "value": 17526.927652604067}, {"lowerIsBetter": false, "replicates": [360.46291026370716, 361.20534823388545, 365.16377495535215, 367.9668829805317, 372.296359879827, 15898.125603091825, 16241.83758162418, 16755.246013929373, 16759.66480670386, 16780.244888979996, 14461.855381446183, 14632.634184145376, 14794.704105917875, 15276.694466110672, 15313.464028758995, 5019.623528235379, 5044.772985215674, 5221.347331583552, 5229.843104706865, 5241.161414173733, 5136.203888397305, 5147.27938088666, 5190.818321358754, 5218.425973142951, 5242.711650859202, 5102.6428150029515, 5136.768845401947, 5147.794088236485, 5150.510701483353, 5151.974240128813, 4983.750812459362, 5167.173252279636, 5184.688918664854, 5191.922121168185, 5192.0, 4548.385967894358, 4580.106879158566, 4606.286025665993, 4609.746463944482, 4621.907561848744, 19799.30702425408, 19852.602947941105, 20315.492112697153, 20346.18615255388, 20862.687059693966, 1970.2414570390504, 1996.9700454493195, 2027.0878104852727, 2186.3768825884545, 2448.5470188015274, 12644.241345519298, 13177.670558236025, 13309.13490623116, 13479.595612131652, 13606.319684015858, 1799.7660304160506, 1829.0945981739123, 1845.9538511537185, 1875.831175194239, 1894.479018269973, 57276.71361643207, 57348.139777903365, 57644.423555763904, 57716.711416442646, 58956.0, 9038.418276801533, 9054.592543335566, 9447.433154010781, 9565.234781217487, 9567.0, 13108.672283192805, 13217.73564528704, 13219.933900330629, 13223.93388033073, 13306.268155251553, 6860.5540639858245, 7007.1591409030225, 7009.053777740041, 7059.399951004119, 7098.787036388917, 6648.368405001614, 6714.597124172566, 6752.864942701119, 6760.391564759097, 6760.797176084725, 5765.221695071112, 5803.709814509258, 5863.2670916135485, 5900.64596124234, 5901.085331773582, 1702.344597330012, 1709.0, 1718.707819670659, 1733.4106403822761, 1752.509297396731, 925.9722208333761, 947.3605316411395, 955.7610597350663, 1001.5493028137367, 1056.498163372392, 16292.348306067624, 16717.55877539624, 16794.6641067178, 16816.663666726596, 17394.304227830744, 877.6182360673128, 910.9544522773834, 924.7549399409162, 971.8347880860271, 986.8618393424927, 9848.21214302854, 9888.851667224997, 10030.498475076218, 10079.249749067436, 10174.694759157237, 364589.0, 369993.0, 383160.0, 384639.0, 386684.0, 57029.0, 60841.99999999911, 62081.37918620846, 62975.6851215741, 63161.684191578745, 926.8053708721109, 927.1192367251139, 928.4754113925476, 928.9907100928904, 941.1717688434134, 14834.332455039334, 14994.625134371292, 15035.473758418402, 15257.0, 15272.160031198391, 29018.76096639233, 30434.54348184823, 30867.845660771553, 31002.844985774926, 31011.0, 40758.59241407548, 41044.79477602593, 41227.1754564913, 41579.16841663211, 42080.0, 7713.6143192841255, 7736.419768517271, 7950.0, 7968.442209045311, 8003.19968003195, 11446.542138314708, 11485.19603627738, 11804.70488237801, 11827.70430739204, 12101.455434505644, 10066.647667331608, 10159.695209143738, 10176.389416635026, 10186.0, 10252.282340236112, 9956.950215248877, 10121.139703125316, 10168.186545076524, 10199.439030853375, 10324.535395907353, 10279.331843430453, 10365.689029329133, 10534.262601617813, 10553.261271710906, 10685.412302323135, 7722.845543089219, 7755.185705501067, 7768.53388796674, 7769.961150194213, 7833.255840695125, 10561.683149505527, 10579.735506612396, 10638.468076595984, 10670.946645266724, 10713.196510261918], "unit": "score", "name": "jquery.html", "value": 8114.581139016393}, {"lowerIsBetter": false, "replicates": [1436.9425222991078, 1460.7516722157231, 1633.2242184962145, 1773.2020590734173, 1826.105208447861, 7474.588897610629, 7569.318761311481, 7710.190430004845, 7719.343855772259, 7766.37868970481, 7793.727219547317, 7901.604919754018, 7902.130765615778, 7904.960475197616, 7944.046714394266, 2221.1226565506613, 2235.8099561537288, 2245.6856040154394, 2247.910083596654, 2248.1119957616734, 2226.7105276314137, 2241.1147596699293, 2288.1991303043883, 2290.3014580552926, 2293.7728315351205, 2163.7836216378314, 2177.6624623183434, 2178.1396348442354, 2207.183342163402, 2254.233560589399, 2238.485148415857, 2270.28486026901, 2285.131649973008, 2285.1544928376607, 2287.4510117571745, 2283.2465286455595, 2303.49323148907, 2303.6429353450244, 2304.3778179891337, 2305.3890718959487, 7845.725399611043, 7885.763427097197, 7906.169852165497, 7930.9603451982375, 7948.642311095957, 4340.7178533395545, 4464.0402313502755, 4467.910641787146, 4477.597016268519, 4478.126765280772, 2905.2155917902046, 2979.970200297991, 2994.82031078136, 3007.3985202959498, 3011.864466099031, 911.7036962987056, 941.7598512379368, 942.4345392764355, 947.8151760406723, 959.7829014975429, 10018.248631352677, 10122.190224782, 10132.34139780912, 10142.492875356204, 10175.338602990782, 3938.330483817758, 3979.064919743858, 4039.111395492987, 4045.7370270932297, 4139.048018955657, 8195.467294625949, 8209.425340226127, 8243.175682431709, 8243.340532757366, 8269.917300827035, 4389.100234451929, 4389.473263208435, 4402.229609818269, 4403.031333106712, 4411.404460397805, 3900.4929359183693, 3962.7622342659533, 3989.0027493126718, 4011.659008984269, 4013.1973605279063, 3378.6621337866018, 3395.7792743471596, 3407.4377727674655, 3425.383430982447, 3451.11996440911, 1009.8030883977701, 1014.6905193915926, 1017.0287375556326, 1027.3989716016065, 1034.9586016559404, 997.5211898288862, 1206.7224538356227, 1212.1938910624335, 1248.950041998328, 1257.415301884628, 6963.059986901805, 7043.436525077981, 7060.293970603001, 7081.185663648644, 7124.50128491011, 471.62505807882815, 484.9830255941025, 486.6885193476178, 488.577380565808, 492.4902725678905, 5556.249906262684, 5594.804181853616, 5595.608307418523, 5628.915566266509, 5938.732757025944, 9189.172974432333, 9484.241260699127, 9496.620135194653, 9512.762180945532, 9517.0, 9128.041555636695, 9247.76880577991, 9289.72130836076, 9367.063293670579, 9426.905730942603, 6762.526623136431, 6867.965660171667, 6885.862282754317, 6890.0, 6899.448044156456, 7137.1435427749, 7302.488825782144, 7311.634418278958, 7316.341529262392, 7461.179270280156, 8273.958630206811, 8316.085230624747, 8363.038250601016, 8432.114627964222, 8505.192006759347, 8454.66181352739, 8563.186497282748, 8595.828083438171, 8641.56792160402, 8649.783755406164, 6176.4441200292185, 6985.266547012694, 7337.229590892879, 7414.740484083031, 7450.403967682464, 6987.1266091738535, 7037.929620703728, 7038.753643622448, 7044.401225895856, 7080.362767350757, 5969.820905372846, 6211.13044173807, 6251.6561589113035, 6257.2491301044165, 6297.023961286008, 6175.814725558241, 6239.814435257286, 6255.374462553708, 6256.530760192913, 6314.968425157845, 6640.468762499085, 6692.732290708317, 6722.932770672231, 6809.080774095433, 6881.862362752817, 6476.708548115252, 6542.901856472062, 6543.934560654523, 6546.476281897532, 6559.770408035887, 6287.717052732546, 6581.276059633341, 6586.275509693834, 6592.143021407186, 6617.636030018202], "unit": "score", "name": "mootools.html", "value": 4262.534497354319}, {"lowerIsBetter": false, "replicates": [333.67300045954966, 337.56622739779374, 338.5717067909097, 339.37385523708747, 341.5679165855192, 5726.0, 5768.163616275638, 5792.333881603617, 5804.506616937555, 5807.0, 5720.914186287209, 5727.599068065237, 5743.138529220619, 5744.741486633101, 5746.798862039829, 4282.186384586918, 4397.384366188736, 4398.780060996937, 4405.8237670493145, 4474.955250447487, 4403.471583409995, 4418.955810441887, 4435.179491794029, 4440.578145076212, 4443.355713421552, 4381.320895261224, 4398.626116780078, 4408.955910440903, 4417.359482874981, 4418.0, 4399.42807435033, 4414.536473670267, 4430.844920427802, 4432.556744325574, 4434.5343738907595, 3836.482074919878, 3859.1509867829313, 3866.149447121629, 3873.0511024798825, 3873.283442563135, 5568.0, 5574.358948720908, 5579.69311687857, 5586.804461843816, 5595.132754423071, 4868.150102960888, 5083.720395378253, 5086.898262034739, 5091.719955402488, 5093.465186155438, 6041.275046994345, 6071.908921366183, 6073.392660733935, 6074.696265186767, 6089.756409743605, 4172.812223449952, 4173.749575025508, 4182.790860456965, 4192.937105943413, 4193.203291374629, 6325.905111423286, 6391.968040159816, 6398.680065996681, 6401.263854656681, 6426.132472116251, 4241.045764702979, 4267.210566045292, 4269.274223382033, 4271.337942618899, 4279.0, 4346.565343465628, 4347.500037495665, 4348.34774783785, 4349.543297953669, 4356.6514678825615, 4870.586000190024, 5036.0, 5057.620678449131, 5094.439611642716, 5095.49682843562, 6078.300995385499, 6082.2093127893095, 6090.665013424215, 6091.360407157184, 6111.205543279434, 1526.5496678479876, 1553.5882991007393, 1553.906765594068, 1554.4637100200414, 1557.314781496138, 985.4186030242048, 1016.5425558498705, 1021.8824835143905, 1023.3143793658267, 1028.573142146001, 5376.892462150735, 5377.4084850666395, 5384.0847056000575, 5396.541293989976, 5399.919001214985, 6283.183186185858, 6300.527460440485, 6328.272248691459, 6337.936620633827, 6341.587796793194, 5190.766415511311, 5324.9733751331, 5374.48942350476, 5394.676319420847, 5413.10683737179, 3430.451127819537, 3454.9136271592906, 3460.1522626956557, 3461.1174150591437, 3469.705075068597, 6064.787732429344, 6112.969435152796, 6114.144019837227, 6114.991026480581, 6119.908201376983, 6547.476201903836, 6552.246491653425, 6561.507886908405, 6583.308752581006, 6591.6374599397495, 6080.8783824324155, 6111.266648002179, 6117.877642447126, 6132.50939924805, 6136.079588061826, 6235.812925612239, 6290.685465726787, 6314.4632706218645, 6389.169407976933, 6399.520035997226, 6182.845428864314, 6241.531885108726, 6252.687365631791, 6258.186435763321, 6260.186175797117, 6093.0, 6095.05526643371, 6097.390260973867, 6100.054491553817, 6115.4190351916495, 4426.402435671143, 4464.15181115587, 4468.352088947149, 4471.575200355961, 4475.239209334486, 6201.534884883742, 6250.3749625037135, 6307.558470906993, 6322.083297921868, 6415.133956915756, 6142.723577438936, 6145.84635384119, 6146.354632763674, 6150.323464419, 6152.230971128609, 6098.054801505774, 6142.600730952474, 6148.938510614837, 6152.354002829638, 6159.106929495288, 5931.703414829155, 5938.0, 5990.281166260077, 5998.190244316961, 5999.040153575582, 6051.818445446644, 6122.867269555147, 6145.66198859067, 6192.969035154795, 6206.72069756853, 5946.672932988554, 5975.0, 5981.880362392815, 5988.37122102173, 5989.101634754822], "unit": "score", "name": "prototype.html", "value": 4571.723159749234}, {"lowerIsBetter": false, "replicates": [561.9943800561995, 566.1875209074977, 570.6718636783851, 586.9236999190105, 620.0606081786095, 10700.39494075889, 11277.830832537507, 11376.488058037397, 11391.202615816896, 11459.942700286487, 10793.83809242862, 10895.618653347134, 12647.494100236003, 12982.675433114153, 13003.089783715144, 704.89426586012, 859.5057841740993, 863.5552690364436, 866.4844417571551, 876.2114097312437, 856.6787454704486, 857.4426622695266, 858.4291446188278, 858.7853036740814, 859.3468963587687, 893.0399820193286, 895.7133717210495, 898.5058217980085, 899.6266549381999, 903.0427746588637, 888.5601627194548, 890.8218356328728, 894.7941973346102, 895.7984453497976, 898.191627535217, 663.2207156590986, 663.7942237906265, 667.0994157886814, 669.4744625469032, 670.6378555579981, 11762.941185294105, 11828.172027958046, 11852.466639001266, 11866.52533898643, 11877.643670689893, 11640.476178571984, 11792.941035294854, 12059.517619295219, 12075.698107547294, 12267.509299628004, 9418.481983490905, 9477.597769271519, 9603.279754018477, 9700.563474643657, 9787.070228328297, 2917.3435976905025, 2927.487689654302, 2935.5890175375466, 2937.456570534458, 2985.5372417275357, 42757.358639620434, 42919.92700182489, 43109.35335969963, 43312.78343608294, 43488.130237395395, 8630.352723545759, 9191.770205744777, 9236.584353703965, 9245.72262832116, 9266.258699304039, 16564.0, 16694.4156954506, 16695.081770502497, 16767.329306827833, 16892.662146756997, 5806.593538452352, 5861.648301101947, 6097.115918191837, 6308.274548426898, 6649.168853893263, 3700.20445604191, 3726.832292546788, 3733.5706393765063, 3800.1449673823167, 3833.3100041992707, 2656.4288677934337, 2682.262377846108, 2684.167907948542, 2684.691260505028, 2687.6774787025684, 514.2851436503263, 563.3690266901096, 571.5741772379604, 579.7275280618103, 583.5069366385397, 403.13125215161375, 422.06091446531224, 443.6406510726322, 497.4031162604816, 517.0666946162138, 6812.897806532906, 6888.345607167411, 7103.2541583133025, 7115.430765538848, 7259.782206533812, 516.4964159944025, 520.4873199898097, 535.3094508084528, 537.1942086869695, 543.1282791120274, 3399.9320013600086, 3413.385590593717, 3489.7382696297877, 3495.545579074704, 3506.386382383073, 49142.0, 51413.0, 51779.0, 51805.222921656205, 51942.0, 44778.55221447744, 45245.0, 45248.773756131006, 46580.301295480596, 46611.30083048757, 39344.60655393467, 40366.19267614631, 40474.19051618951, 41387.0, 41590.168196635896, 6485.708143133665, 6532.640704761284, 6692.732290708317, 6742.08981787472, 6984.3364880336285, 30989.53515697221, 31113.844430777703, 31439.842800786766, 32071.518927215642, 32233.67766322307, 37028.25943481169, 40645.0, 41089.79455102825, 41302.1739565213, 41556.0, 8412.453190542596, 8500.489970601784, 8503.702370417006, 8506.404551681571, 8511.702090426805, 9051.59267832936, 9085.454872707658, 9111.453312801254, 9316.860247096434, 9390.765230869283, 8510.957445212734, 8565.100664430394, 8610.569471526524, 8653.740387788124, 8684.348673849612, 8369.916300836914, 8447.619857106321, 8478.406511544134, 8504.0, 8597.0, 8686.478811271343, 8748.47509149453, 8812.118788121137, 8902.643894244158, 8917.152870477294, 7051.929480705127, 7197.424206063607, 7308.342249197598, 7450.031495905498, 7643.273888980538, 7431.331180193809, 7623.923760762322, 7706.845863082819, 7758.806029849299, 7851.764447066597], "unit": "score", "name": "yui.html", "value": 5472.663542116749}], "name": "dromaeo_css", "value": 6436.690623371698}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 106.0, 113.0, 114.0, 113.0, 103.0, 113.0, 114.0, 112.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [159.0, 139.0, 161.0, 167.0, 136.0, 158.0, 159.0, 140.0, 185.0, 138.0], "unit": "score", "name": "audio-beat-detection", "value": 154.2}, {"lowerIsBetter": true, "replicates": [161.0, 163.0, 162.0, 162.0, 163.0, 163.0, 163.0, 161.0, 162.0, 163.0], "unit": "score", "name": "audio-dft", "value": 162.3}, {"lowerIsBetter": true, "replicates": [83.0, 77.0, 77.0, 158.0, 78.0, 78.0, 77.0, 79.0, 160.0, 77.0], "unit": "score", "name": "audio-fft", "value": 94.4}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 143.0, 95.0, 95.0, 94.0, 143.0, 95.0, 95.0, 134.0], "unit": "score", "name": "audio-oscillator", "value": 108.3}, {"lowerIsBetter": true, "replicates": [117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 120.0, 118.0, 118.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.8}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 146.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 70.0, 69.0, 70.0, 67.0, 67.0, 69.0, 78.0, 70.0], "unit": "score", "name": "json-parse-financial", "value": 69.6}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [80.0, 79.0, 81.0, 82.0, 82.0, 80.0, 81.0, 83.0, 81.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.1}, {"lowerIsBetter": true, "replicates": [121.0, 118.0, 130.0, 132.0, 122.0, 121.0, 124.0, 119.0, 126.0, 132.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.5}, {"lowerIsBetter": true, "replicates": [138.0, 145.0, 150.0, 147.0, 143.0, 130.0, 148.0, 145.0, 155.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.1}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 72.0, 67.0, 67.0, 66.0, 68.0, 69.0, 68.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1542.5999999999997}]} 16:22:01 INFO - Return code: 0 16:22:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:22:01 ERROR - # TBPL SUCCESS # 16:22:01 INFO - Running post-action listener: _resource_record_post_action 16:22:01 INFO - Running post-run listener: _resource_record_post_run 16:22:02 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 16384; Write bytes: 486785024; Read time: 32; Write time: 490972 16:22:02 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 238125056; Read time: 0; Write time: 292876 16:22:02 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 16384; Write bytes: 248451072; Read time: 32; Write time: 198072 16:22:02 INFO - Running post-run listener: _upload_blobber_files 16:22:02 INFO - Blob upload gear active. 16:22:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:02 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 16:22:02 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:22:02 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:22:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:03 INFO - (blobuploader) - INFO - Done attempting. 16:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:04 INFO - (blobuploader) - INFO - Done attempting. 16:22:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:04 INFO - Return code: 0 16:22:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/48255c6a5c8f87cbcb6d74f4aead99e38718efd9b0e0af9c7ee87876250cbc1403b3b19402b676bc1a4551bc5fbf2c7c022a65ee66d08f2043f13293980a8984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6a27c8f6a23f3b960c2d1c6a2852e3222edb1341405cb9a91acce7f8866cb8bbe0e9dcc7449d4f3801a844defb8f48780b5b88944058ed3cd8f4f562eaa45e5e"} 16:22:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:04 INFO - Contents: 16:22:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/48255c6a5c8f87cbcb6d74f4aead99e38718efd9b0e0af9c7ee87876250cbc1403b3b19402b676bc1a4551bc5fbf2c7c022a65ee66d08f2043f13293980a8984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6a27c8f6a23f3b960c2d1c6a2852e3222edb1341405cb9a91acce7f8866cb8bbe0e9dcc7449d4f3801a844defb8f48780b5b88944058ed3cd8f4f562eaa45e5e"} 16:22:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:04 INFO - Copying logs to upload dir... 16:22:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.990026 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-06-30 16:22:04.542584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 16:22:04.546515) ========= 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=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 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-Non-PGO/sha512/48255c6a5c8f87cbcb6d74f4aead99e38718efd9b0e0af9c7ee87876250cbc1403b3b19402b676bc1a4551bc5fbf2c7c022a65ee66d08f2043f13293980a8984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6a27c8f6a23f3b960c2d1c6a2852e3222edb1341405cb9a91acce7f8866cb8bbe0e9dcc7449d4f3801a844defb8f48780b5b88944058ed3cd8f4f562eaa45e5e"} build_url:https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/LVluh5ZyR7aj1R51HJfO5A/artifacts/public/build/firefox-47.0.2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/48255c6a5c8f87cbcb6d74f4aead99e38718efd9b0e0af9c7ee87876250cbc1403b3b19402b676bc1a4551bc5fbf2c7c022a65ee66d08f2043f13293980a8984", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6a27c8f6a23f3b960c2d1c6a2852e3222edb1341405cb9a91acce7f8866cb8bbe0e9dcc7449d4f3801a844defb8f48780b5b88944058ed3cd8f4f562eaa45e5e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 16:22:04.572147) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:22:04.572472) ========= 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=920470bf432669ad7e3a5387000001ca-1467327693.809128-525247832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004640 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 16:22:04.658871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 16:22:04.659246) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 16:22:04.659574) ========= ========= Total master_lag: 0.18 =========