builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-004 starttime: 1461474818.32 results: success (0) buildid: 20160423193608 builduid: e1a7c9fe0eea415a8a2cb728c1da029f revision: ab8a76ac7b34db20dd3c220c9846826334f3efdc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.318290) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.318697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.318969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.333061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.333537) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-23 22:13:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 95.0M=0s 2016-04-23 22:13:38 (95.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127844 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.472126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.472461) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015578 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.521257) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:38.521554) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ab8a76ac7b34db20dd3c220c9846826334f3efdc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ab8a76ac7b34db20dd3c220c9846826334f3efdc --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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-23 22:13:38,603 truncating revision to first 12 chars 2016-04-23 22:13:38,603 Setting DEBUG logging. 2016-04-23 22:13:38,603 attempt 1/10 2016-04-23 22:13:38,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ab8a76ac7b34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 22:13:38,837 unpacking tar archive at: mozilla-central-ab8a76ac7b34/testing/mozharness/ program finished with exit code 0 elapsedTime=0.508565 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:39.042791) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:39.043133) ========= script_repo_revision: ab8a76ac7b34db20dd3c220c9846826334f3efdc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:39.043497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:39.043750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 22:13:39.051944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-04-23 22:13:39.052230) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:13:39 INFO - MultiFileLogger online at 20160423 22:13:39 in /builds/slave/test 22:13:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:13:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:13:39 INFO - {'append_to_log': False, 22:13:39 INFO - 'base_work_dir': '/builds/slave/test', 22:13:39 INFO - 'blob_upload_branch': 'Firefox', 22:13:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:13:39 INFO - 'branch': 'Firefox', 22:13:39 INFO - 'buildbot_json_path': 'buildprops.json', 22:13:39 INFO - 'config_files': ('talos/linux_config.py',), 22:13:39 INFO - 'default_actions': ('clobber', 22:13:39 INFO - 'read-buildbot-config', 22:13:39 INFO - 'download-and-extract', 22:13:39 INFO - 'populate-webroot', 22:13:39 INFO - 'create-virtualenv', 22:13:39 INFO - 'install', 22:13:39 INFO - 'run-tests'), 22:13:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:13:39 INFO - 'download_minidump_stackwalk': True, 22:13:39 INFO - 'download_symbols': 'ondemand', 22:13:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:13:39 INFO - 'tooltool.py': '/tools/tooltool.py', 22:13:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:13:39 INFO - '/tools/misc-python/virtualenv.py')}, 22:13:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:13:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:13:39 INFO - 'installer_path': 'installer.exe', 22:13:39 INFO - 'log_level': 'info', 22:13:39 INFO - 'log_name': 'talos', 22:13:39 INFO - 'log_to_console': True, 22:13:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:13:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:13:39 INFO - 'opt_config_files': (), 22:13:39 INFO - 'pip_index': False, 22:13:39 INFO - 'sps_profile': False, 22:13:39 INFO - 'sps_profile_interval': 0, 22:13:39 INFO - 'suite': 'dromaeojs-e10s', 22:13:39 INFO - 'system_bits': '32', 22:13:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:13:39 INFO - 'title': 'talos-linux64-ix-004', 22:13:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:13:39 INFO - 'use_talos_json': True, 22:13:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:13:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:13:39 INFO - 'work_dir': 'build'} 22:13:39 INFO - ##### 22:13:39 INFO - ##### Running clobber step. 22:13:39 INFO - ##### 22:13:39 INFO - Running pre-action listener: _resource_record_pre_action 22:13:39 INFO - Running main action method: clobber 22:13:39 INFO - rmtree: /builds/slave/test/build 22:13:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:13:39 INFO - Running post-action listener: _resource_record_post_action 22:13:39 INFO - ##### 22:13:39 INFO - ##### Running read-buildbot-config step. 22:13:39 INFO - ##### 22:13:39 INFO - Running pre-action listener: _resource_record_pre_action 22:13:39 INFO - Running main action method: read_buildbot_config 22:13:39 INFO - Using buildbot properties: 22:13:39 INFO - { 22:13:39 INFO - "project": "", 22:13:39 INFO - "product": "firefox", 22:13:39 INFO - "script_repo_revision": "production", 22:13:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:13:39 INFO - "repository": "", 22:13:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:13:39 INFO - "buildid": "20160423193608", 22:13:39 INFO - "pgo_build": "True", 22:13:39 INFO - "basedir": "/builds/slave/test", 22:13:39 INFO - "buildnumber": 1, 22:13:39 INFO - "slavename": "talos-linux64-ix-004", 22:13:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:13:39 INFO - "platform": "ubuntu64_hw", 22:13:39 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:13:39 INFO - "branch": "mozilla-central", 22:13:39 INFO - "repo_path": "mozilla-central", 22:13:39 INFO - "revision": "ab8a76ac7b34db20dd3c220c9846826334f3efdc", 22:13:39 INFO - "stage_platform": "linux64-pgo", 22:13:39 INFO - "builduid": "e1a7c9fe0eea415a8a2cb728c1da029f", 22:13:39 INFO - "slavebuilddir": "test" 22:13:39 INFO - } 22:13:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 22:13:39 INFO - Running post-action listener: _resource_record_post_action 22:13:39 INFO - ##### 22:13:39 INFO - ##### Running download-and-extract step. 22:13:39 INFO - ##### 22:13:39 INFO - Running pre-action listener: _resource_record_pre_action 22:13:39 INFO - Running main action method: download_and_extract 22:13:39 INFO - mkdir: /builds/slave/test/build/tests 22:13: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')]} 22:13:39 INFO - https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:13:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:13:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:13:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:13:40 INFO - Downloaded 1361 bytes. 22:13:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:13:40 INFO - Using the following test package requirements: 22:13:40 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:13:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:13:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:13:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'jsshell-linux-x86_64.zip'], 22:13:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:13:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:13:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:13:40 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:13:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:13:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:13:40 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:13:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:13:40 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')]} 22:13:40 INFO - https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:13:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:13:41 INFO - Downloaded 23858063 bytes. 22:13:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:13:42 INFO - Return code: 0 22:13:42 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:13: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')]} 22:13:42 INFO - https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:13:48 INFO - Downloaded 23858063 bytes. 22:13:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:13:50 INFO - Return code: 0 22:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:50 INFO - https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:13:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:13:51 INFO - Downloaded 10840145 bytes. 22:13:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:13:51 INFO - Return code: 0 22:13: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')]} 22:13:51 INFO - https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:13:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:13:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:13:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:13:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:13:52 INFO - Downloaded 58355358 bytes. 22:13:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:13:52 INFO - mkdir: /builds/slave/test/properties 22:13:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:13:52 INFO - Writing to file /builds/slave/test/properties/build_url 22:13:52 INFO - Contents: 22:13:52 INFO - build_url:https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:13:53 INFO - Running post-action listener: _resource_record_post_action 22:13:53 INFO - Running post-action listener: set_extra_try_arguments 22:13:53 INFO - ##### 22:13:53 INFO - ##### Running populate-webroot step. 22:13:53 INFO - ##### 22:13:53 INFO - Running pre-action listener: _resource_record_pre_action 22:13:53 INFO - Running main action method: populate_webroot 22:13:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:13:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:13:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:13:53 INFO - '--tppagecycles', 22:13:53 INFO - '7'], 22:13:53 INFO - 'tests': ['tsvgm']}}, 22:13:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:13:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:13:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:13:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:13:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['damp', 'tps']}, 22:13:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'tests': ['damp', 'tps']}, 22:13:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['dromaeo_dom']}, 22:13:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:13:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'other-e10s': {'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['a11yr', 22:13:53 INFO - 'ts_paint', 22:13:53 INFO - 'tpaint', 22:13:53 INFO - 'sessionrestore', 22:13:53 INFO - 'sessionrestore_no_auto_restore', 22:13:53 INFO - 'tabpaint']}, 22:13:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['tsvgx', 22:13:53 INFO - 'tsvgr_opacity', 22:13:53 INFO - 'tart', 22:13:53 INFO - 'tscrollx', 22:13:53 INFO - 'cart']}, 22:13:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:13:53 INFO - 'tsvgr_opacity', 22:13:53 INFO - 'tart', 22:13:53 INFO - 'tscrollx', 22:13:53 INFO - 'cart']}, 22:13:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'talos_options': ['--disable-e10s'], 22:13:53 INFO - 'tests': ['tp5o']}, 22:13:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'tests': ['tp5o']}, 22:13:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'talos_options': ['--disable-e10s', 22:13:53 INFO - '--xperf_path', 22:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:53 INFO - 'tests': ['tp5n']}, 22:13:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:53 INFO - 'talos_options': ['--xperf_path', 22:13:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:53 INFO - 'tests': ['tp5n']}}, 22:13:53 INFO - 'talos.zip': {'path': '', 22:13:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:13:53 INFO - Running post-action listener: _resource_record_post_action 22:13:53 INFO - ##### 22:13:53 INFO - ##### Running create-virtualenv step. 22:13:53 INFO - ##### 22:13:53 INFO - Running pre-action listener: _resource_record_pre_action 22:13:53 INFO - Running main action method: create_virtualenv 22:13:53 INFO - Creating virtualenv /builds/slave/test/build/venv 22:13:53 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 22:13:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:13:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:13:53 INFO - Using real prefix '/usr' 22:13:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:13:54 INFO - Installing distribute.............................................................................................................................................................................................done. 22:13:56 INFO - Installing pip.................done. 22:13:56 INFO - Return code: 0 22:13:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:56 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:13:56 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 22:13:56 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 22:13:56 INFO - Using env: {'DISPLAY': ':0', 22:13:56 INFO - 'HOME': '/home/cltbld', 22:13:56 INFO - 'LANG': 'en_US.UTF-8', 22:13:56 INFO - 'LANGUAGE': 'en_US:en', 22:13:56 INFO - 'LOGNAME': 'cltbld', 22:13:56 INFO - 'MAIL': '/var/mail/cltbld', 22:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:56 INFO - 'MOZ_NO_REMOTE': '1', 22:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:56 INFO - 'NO_EM_RESTART': '1', 22:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:56 INFO - 'PWD': '/builds/slave/test', 22:13:56 INFO - 'SHELL': '/bin/bash', 22:13:56 INFO - 'SHLVL': '1', 22:13:56 INFO - 'TERM': 'linux', 22:13:56 INFO - 'TMOUT': '86400', 22:13:56 INFO - 'USER': 'cltbld', 22:13:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:56 INFO - '_': '/tools/buildbot/bin/python'} 22:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:56 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 22:13:56 INFO - Cleaning up... 22:13:56 INFO - Return code: 0 22:13:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:56 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:13:56 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 22:13:56 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 22:13:56 INFO - Using env: {'DISPLAY': ':0', 22:13:56 INFO - 'HOME': '/home/cltbld', 22:13:56 INFO - 'LANG': 'en_US.UTF-8', 22:13:56 INFO - 'LANGUAGE': 'en_US:en', 22:13:56 INFO - 'LOGNAME': 'cltbld', 22:13:56 INFO - 'MAIL': '/var/mail/cltbld', 22:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:56 INFO - 'MOZ_NO_REMOTE': '1', 22:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:56 INFO - 'NO_EM_RESTART': '1', 22:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:56 INFO - 'PWD': '/builds/slave/test', 22:13:56 INFO - 'SHELL': '/bin/bash', 22:13:56 INFO - 'SHLVL': '1', 22:13:56 INFO - 'TERM': 'linux', 22:13:56 INFO - 'TMOUT': '86400', 22:13:56 INFO - 'USER': 'cltbld', 22:13:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:56 INFO - '_': '/tools/buildbot/bin/python'} 22:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:56 INFO - Downloading/unpacking psutil>=0.7.1 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13: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 22:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:13:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:58 INFO - Installing collected packages: psutil 22:13:58 INFO - Running setup.py install for psutil 22:13:58 INFO - building 'psutil._psutil_linux' extension 22:13:58 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 22:13:59 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 22:13:59 INFO - building 'psutil._psutil_posix' extension 22:13:59 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 22:13:59 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 22:13:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:59 INFO - Successfully installed psutil 22:13:59 INFO - Cleaning up... 22:13:59 INFO - Return code: 0 22:13:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:13:59 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')]} 22:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:59 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')]} 22:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:59 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:13:59 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 22:13:59 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 22:13:59 INFO - Using env: {'DISPLAY': ':0', 22:13:59 INFO - 'HOME': '/home/cltbld', 22:13:59 INFO - 'LANG': 'en_US.UTF-8', 22:13:59 INFO - 'LANGUAGE': 'en_US:en', 22:13:59 INFO - 'LOGNAME': 'cltbld', 22:13:59 INFO - 'MAIL': '/var/mail/cltbld', 22:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:59 INFO - 'MOZ_NO_REMOTE': '1', 22:13:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:13:59 INFO - 'NO_EM_RESTART': '1', 22:13:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:59 INFO - 'PWD': '/builds/slave/test', 22:13:59 INFO - 'SHELL': '/bin/bash', 22:13:59 INFO - 'SHLVL': '1', 22:13:59 INFO - 'TERM': 'linux', 22:13:59 INFO - 'TMOUT': '86400', 22:13:59 INFO - 'USER': 'cltbld', 22:13:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:59 INFO - '_': '/tools/buildbot/bin/python'} 22:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:13:59 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 22:13:59 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 22:13:59 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 22:13:59 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 22:13:59 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 22:13:59 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 22:14:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:14:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:14:01 INFO - Installing collected packages: mozsystemmonitor 22:14:01 INFO - Running setup.py install for mozsystemmonitor 22:14:01 INFO - Successfully installed mozsystemmonitor 22:14:01 INFO - Cleaning up... 22:14:01 INFO - Return code: 0 22:14:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:14:01 INFO - Using env: {'DISPLAY': ':0', 22:14:01 INFO - 'HOME': '/home/cltbld', 22:14:01 INFO - 'LANG': 'en_US.UTF-8', 22:14:01 INFO - 'LANGUAGE': 'en_US:en', 22:14:01 INFO - 'LOGNAME': 'cltbld', 22:14:01 INFO - 'MAIL': '/var/mail/cltbld', 22:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:01 INFO - 'MOZ_NO_REMOTE': '1', 22:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:01 INFO - 'NO_EM_RESTART': '1', 22:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:01 INFO - 'PWD': '/builds/slave/test', 22:14:01 INFO - 'SHELL': '/bin/bash', 22:14:01 INFO - 'SHLVL': '1', 22:14:01 INFO - 'TERM': 'linux', 22:14:01 INFO - 'TMOUT': '86400', 22:14:01 INFO - 'USER': 'cltbld', 22:14:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:01 INFO - '_': '/tools/buildbot/bin/python'} 22:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:02 INFO - Downloading/unpacking blobuploader==1.2.4 22:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:04 INFO - Downloading blobuploader-1.2.4.tar.gz 22:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:14:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:14:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14: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 22:14:04 INFO - Downloading docopt-0.6.1.tar.gz 22:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:14:04 INFO - Installing collected packages: blobuploader, requests, docopt 22:14:04 INFO - Running setup.py install for blobuploader 22:14:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:14:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:14:05 INFO - Running setup.py install for requests 22:14:05 INFO - Running setup.py install for docopt 22:14:05 INFO - Successfully installed blobuploader requests docopt 22:14:05 INFO - Cleaning up... 22:14:05 INFO - Return code: 0 22:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:05 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')]} 22:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:05 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')]} 22:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:05 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:05 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 22:14:05 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 22:14:05 INFO - Using env: {'DISPLAY': ':0', 22:14:05 INFO - 'HOME': '/home/cltbld', 22:14:05 INFO - 'LANG': 'en_US.UTF-8', 22:14:05 INFO - 'LANGUAGE': 'en_US:en', 22:14:05 INFO - 'LOGNAME': 'cltbld', 22:14:05 INFO - 'MAIL': '/var/mail/cltbld', 22:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:05 INFO - 'MOZ_NO_REMOTE': '1', 22:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:05 INFO - 'NO_EM_RESTART': '1', 22:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:05 INFO - 'PWD': '/builds/slave/test', 22:14:05 INFO - 'SHELL': '/bin/bash', 22:14:05 INFO - 'SHLVL': '1', 22:14:05 INFO - 'TERM': 'linux', 22:14:05 INFO - 'TMOUT': '86400', 22:14:05 INFO - 'USER': 'cltbld', 22:14:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:05 INFO - '_': '/tools/buildbot/bin/python'} 22:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:14:05 INFO - Running setup.py (path:/tmp/pip-W7H4tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:14:05 INFO - Running setup.py (path:/tmp/pip-wzaj6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:14:05 INFO - Running setup.py (path:/tmp/pip-_1aW3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:14:05 INFO - Running setup.py (path:/tmp/pip-BptjXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:14:06 INFO - Running setup.py (path:/tmp/pip-O5eO1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:14:06 INFO - Running setup.py (path:/tmp/pip-zLgkct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:14:06 INFO - Running setup.py (path:/tmp/pip-pv8DGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:14:06 INFO - Running setup.py (path:/tmp/pip-wHjBcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:14:06 INFO - Running setup.py (path:/tmp/pip-mkGIRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:14:06 INFO - Running setup.py (path:/tmp/pip-KkzwsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:14:06 INFO - Running setup.py (path:/tmp/pip-PYVbed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:14:06 INFO - Running setup.py (path:/tmp/pip-XH_3Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:14:06 INFO - Running setup.py (path:/tmp/pip-ctnrV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:14:06 INFO - Running setup.py (path:/tmp/pip-hGnZ2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:06 INFO - Running setup.py (path:/tmp/pip-fKsIR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:14:06 INFO - Running setup.py (path:/tmp/pip-PIilVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:14:06 INFO - Running setup.py (path:/tmp/pip-0VU8m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:14:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:14:06 INFO - Running setup.py install for manifestparser 22:14:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for mozcrash 22:14:07 INFO - Running setup.py install for mozdebug 22:14:07 INFO - Running setup.py install for mozdevice 22:14:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for mozfile 22:14:07 INFO - Running setup.py install for mozhttpd 22:14:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for mozinfo 22:14:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for mozInstall 22:14:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for mozleak 22:14:07 INFO - Running setup.py install for mozlog 22:14:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:14:07 INFO - Running setup.py install for moznetwork 22:14:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Running setup.py install for mozprocess 22:14:08 INFO - Running setup.py install for mozprofile 22:14:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Running setup.py install for mozrunner 22:14:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Running setup.py install for mozscreenshot 22:14:08 INFO - Running setup.py install for moztest 22:14:08 INFO - Running setup.py install for mozversion 22:14:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:14:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:14:08 INFO - Cleaning up... 22:14:08 INFO - Return code: 0 22:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:08 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:08 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 22:14:08 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 22:14:08 INFO - Using env: {'DISPLAY': ':0', 22:14:08 INFO - 'HOME': '/home/cltbld', 22:14:08 INFO - 'LANG': 'en_US.UTF-8', 22:14:08 INFO - 'LANGUAGE': 'en_US:en', 22:14:08 INFO - 'LOGNAME': 'cltbld', 22:14:08 INFO - 'MAIL': '/var/mail/cltbld', 22:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:08 INFO - 'MOZ_NO_REMOTE': '1', 22:14:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:08 INFO - 'NO_EM_RESTART': '1', 22:14:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:08 INFO - 'PWD': '/builds/slave/test', 22:14:08 INFO - 'SHELL': '/bin/bash', 22:14:08 INFO - 'SHLVL': '1', 22:14:08 INFO - 'TERM': 'linux', 22:14:08 INFO - 'TMOUT': '86400', 22:14:08 INFO - 'USER': 'cltbld', 22:14:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:08 INFO - '_': '/tools/buildbot/bin/python'} 22:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:14:08 INFO - Running setup.py (path:/tmp/pip-QYhaRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:14:09 INFO - Running setup.py (path:/tmp/pip-NFyqzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:14:09 INFO - Running setup.py (path:/tmp/pip-bhIgKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:14:09 INFO - Running setup.py (path:/tmp/pip-9i2kLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:14:09 INFO - Running setup.py (path:/tmp/pip-KRToy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:14:09 INFO - Running setup.py (path:/tmp/pip-hbEFDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:14:09 INFO - Running setup.py (path:/tmp/pip-GekIqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:14:09 INFO - Running setup.py (path:/tmp/pip-rgEH1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:14:09 INFO - Running setup.py (path:/tmp/pip-s8h0TC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:14:09 INFO - Running setup.py (path:/tmp/pip-DzWB9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:14:09 INFO - Running setup.py (path:/tmp/pip-m2Z29Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:14:09 INFO - Running setup.py (path:/tmp/pip-zvExMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:14:09 INFO - Running setup.py (path:/tmp/pip-zFMAzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:14:09 INFO - Running setup.py (path:/tmp/pip-NjMGRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:14:09 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)) 22:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:09 INFO - Running setup.py (path:/tmp/pip-mvriNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:14:10 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)) 22:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:14:10 INFO - Running setup.py (path:/tmp/pip-YxZ9wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:14:10 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)) 22:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:14:10 INFO - Running setup.py (path:/tmp/pip-ZHfnLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:14:10 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)) 22:14:10 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)) 22:14:10 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)) 22:14:10 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)) 22:14:10 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)) 22:14:10 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)) 22:14:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:14:10 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 22:14:10 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 22:14:10 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 22:14:10 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 22:14:10 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 22:14:10 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 22:14:12 INFO - Downloading blessings-1.6.tar.gz 22:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:14:12 INFO - Installing collected packages: blessings 22:14:12 INFO - Running setup.py install for blessings 22:14:12 INFO - Successfully installed blessings 22:14:12 INFO - Cleaning up... 22:14:12 INFO - Return code: 0 22:14:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:14:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:12 INFO - Reading from file tmpfile_stdout 22:14:12 INFO - Current package versions: 22:14:12 INFO - argparse == 1.2.1 22:14:12 INFO - blessings == 1.6 22:14:12 INFO - blobuploader == 1.2.4 22:14:12 INFO - docopt == 0.6.1 22:14:12 INFO - manifestparser == 1.1 22:14:12 INFO - mozInstall == 1.12 22:14:12 INFO - mozcrash == 0.17 22:14:12 INFO - mozdebug == 0.1 22:14:12 INFO - mozdevice == 0.48 22:14:12 INFO - mozfile == 1.2 22:14:12 INFO - mozhttpd == 0.7 22:14:12 INFO - mozinfo == 0.9 22:14:12 INFO - mozleak == 0.1 22:14:12 INFO - mozlog == 3.1 22:14:12 INFO - moznetwork == 0.27 22:14:12 INFO - mozprocess == 0.22 22:14:12 INFO - mozprofile == 0.28 22:14:12 INFO - mozrunner == 6.11 22:14:12 INFO - mozscreenshot == 0.1 22:14:12 INFO - mozsystemmonitor == 0.0 22:14:12 INFO - moztest == 0.7 22:14:12 INFO - mozversion == 1.4 22:14:12 INFO - psutil == 3.1.1 22:14:12 INFO - requests == 1.2.3 22:14:12 INFO - wsgiref == 0.1.2 22:14:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:12 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')]} 22:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:12 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')]} 22:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:12 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:12 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 22:14:12 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 22:14:12 INFO - Using env: {'DISPLAY': ':0', 22:14:12 INFO - 'HOME': '/home/cltbld', 22:14:12 INFO - 'LANG': 'en_US.UTF-8', 22:14:12 INFO - 'LANGUAGE': 'en_US:en', 22:14:12 INFO - 'LOGNAME': 'cltbld', 22:14:12 INFO - 'MAIL': '/var/mail/cltbld', 22:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:12 INFO - 'MOZ_NO_REMOTE': '1', 22:14:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:12 INFO - 'NO_EM_RESTART': '1', 22:14:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:12 INFO - 'PWD': '/builds/slave/test', 22:14:12 INFO - 'SHELL': '/bin/bash', 22:14:12 INFO - 'SHLVL': '1', 22:14:12 INFO - 'TERM': 'linux', 22:14:12 INFO - 'TMOUT': '86400', 22:14:12 INFO - 'USER': 'cltbld', 22:14:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:12 INFO - '_': '/tools/buildbot/bin/python'} 22:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 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)) 22:14:12 INFO - Cleaning up... 22:14:12 INFO - Return code: 0 22:14:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:14:12 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')]} 22:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:12 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')]} 22:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:12 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:12 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 22:14:12 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 22:14:12 INFO - Using env: {'DISPLAY': ':0', 22:14:12 INFO - 'HOME': '/home/cltbld', 22:14:12 INFO - 'LANG': 'en_US.UTF-8', 22:14:12 INFO - 'LANGUAGE': 'en_US:en', 22:14:12 INFO - 'LOGNAME': 'cltbld', 22:14:12 INFO - 'MAIL': '/var/mail/cltbld', 22:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:12 INFO - 'MOZ_NO_REMOTE': '1', 22:14:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:12 INFO - 'NO_EM_RESTART': '1', 22:14:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:12 INFO - 'PWD': '/builds/slave/test', 22:14:12 INFO - 'SHELL': '/bin/bash', 22:14:12 INFO - 'SHLVL': '1', 22:14:12 INFO - 'TERM': 'linux', 22:14:12 INFO - 'TMOUT': '86400', 22:14:12 INFO - 'USER': 'cltbld', 22:14:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:12 INFO - '_': '/tools/buildbot/bin/python'} 22:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:12 INFO - Downloading/unpacking jsonschema 22:14:12 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 22:14:12 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 22:14:12 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 22:14:12 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 22:14:12 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 22:14:12 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 22:14:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:14:14 INFO - Downloading/unpacking functools32 (from jsonschema) 22:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:14:15 INFO - warning: no files found matching '*.txt' 22:14:15 INFO - no previously-included directories found matching 'build' 22:14:15 INFO - no previously-included directories found matching 'dist' 22:14:15 INFO - no previously-included directories found matching '.git*' 22:14:15 INFO - Installing collected packages: jsonschema, functools32 22:14:15 INFO - Running setup.py install for functools32 22:14:15 INFO - warning: no files found matching '*.txt' 22:14:15 INFO - no previously-included directories found matching 'build' 22:14:15 INFO - no previously-included directories found matching 'dist' 22:14:15 INFO - no previously-included directories found matching '.git*' 22:14:15 INFO - Successfully installed jsonschema functools32 22:14:15 INFO - Cleaning up... 22:14:15 INFO - Return code: 0 22:14:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:14:15 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')]} 22:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:15 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')]} 22:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:15 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 0x7f907f732f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e068a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ed15a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', '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 22:14:15 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 22:14:15 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 22:14:15 INFO - Using env: {'DISPLAY': ':0', 22:14:15 INFO - 'HOME': '/home/cltbld', 22:14:15 INFO - 'LANG': 'en_US.UTF-8', 22:14:15 INFO - 'LANGUAGE': 'en_US:en', 22:14:15 INFO - 'LOGNAME': 'cltbld', 22:14:15 INFO - 'MAIL': '/var/mail/cltbld', 22:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:15 INFO - 'MOZ_NO_REMOTE': '1', 22:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:15 INFO - 'NO_EM_RESTART': '1', 22:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:15 INFO - 'PWD': '/builds/slave/test', 22:14:15 INFO - 'SHELL': '/bin/bash', 22:14:15 INFO - 'SHLVL': '1', 22:14:15 INFO - 'TERM': 'linux', 22:14:15 INFO - 'TMOUT': '86400', 22:14:15 INFO - 'USER': 'cltbld', 22:14:15 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:15 INFO - '_': '/tools/buildbot/bin/python'} 22:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:15 INFO - Downloading/unpacking flake8 22:14:15 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 22:14:15 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 22:14:15 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 22:14:15 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 22:14:15 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 22:14:15 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 22:14:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:14:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:14:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:17 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 22:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:18 INFO - no previously-included directories found matching 'docs/_build' 22:14:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:14:18 INFO - Running setup.py install for pep8 22:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:18 INFO - no previously-included directories found matching 'docs/_build' 22:14:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:14:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:14:18 INFO - Cleaning up... 22:14:18 INFO - Return code: 0 22:14:18 INFO - Running post-action listener: _resource_record_post_action 22:14:18 INFO - Running post-action listener: _start_resource_monitoring 22:14:18 INFO - Starting resource monitoring. 22:14:18 INFO - ##### 22:14:18 INFO - ##### Running install step. 22:14:18 INFO - ##### 22:14:18 INFO - Running pre-action listener: _resource_record_pre_action 22:14:18 INFO - Running main action method: install 22:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:18 INFO - Reading from file tmpfile_stdout 22:14:18 INFO - Detecting whether we're running mozinstall >=1.0... 22:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:14:18 INFO - Reading from file tmpfile_stdout 22:14:18 INFO - Output received: 22:14:18 INFO - Usage: mozinstall [options] installer 22:14:18 INFO - Options: 22:14:18 INFO - -h, --help show this help message and exit 22:14:18 INFO - -d DEST, --destination=DEST 22:14:18 INFO - Directory to install application into. [default: 22:14:18 INFO - "/builds/slave/test"] 22:14:18 INFO - --app=APP Application being installed. [default: firefox] 22:14:18 INFO - mkdir: /builds/slave/test/build/application 22:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:14:35 INFO - Reading from file tmpfile_stdout 22:14:35 INFO - Output received: 22:14:35 INFO - /builds/slave/test/build/application/firefox/firefox 22:14:35 INFO - Running post-action listener: _resource_record_post_action 22:14:35 INFO - ##### 22:14:35 INFO - ##### Running run-tests step. 22:14:35 INFO - ##### 22:14:35 INFO - Running pre-action listener: _resource_record_pre_action 22:14:35 INFO - Running main action method: run_tests 22:14:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:14:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:14:35 INFO - Python 2.7.3 22:14:35 INFO - Return code: 0 22:14:35 INFO - grabbing minidump binary from tooltool 22:14:35 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')]} 22:14:35 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 0x1f60410>, '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 0x1f61310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f617a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:14:35 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 22:14:35 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 22:14:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:14:35 INFO - Return code: 0 22:14:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:14:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:14:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:14:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:14:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:14:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:14:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:14:36 INFO - Return code: 0 22:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 22:14:36 INFO - Using env: {'DISPLAY': ':0', 22:14:36 INFO - 'HOME': '/home/cltbld', 22:14:36 INFO - 'LANG': 'en_US.UTF-8', 22:14:36 INFO - 'LANGUAGE': 'en_US:en', 22:14:36 INFO - 'LOGNAME': 'cltbld', 22:14:36 INFO - 'MAIL': '/var/mail/cltbld', 22:14:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:14:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:36 INFO - 'MOZ_NO_REMOTE': '1', 22:14:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:14:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:14:36 INFO - 'NO_EM_RESTART': '1', 22:14:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:36 INFO - 'PWD': '/builds/slave/test', 22:14:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:14:36 INFO - 'SHELL': '/bin/bash', 22:14:36 INFO - 'SHLVL': '1', 22:14:36 INFO - 'TERM': 'linux', 22:14:36 INFO - 'TMOUT': '86400', 22:14:36 INFO - 'USER': 'cltbld', 22:14:36 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845', 22:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:36 INFO - '_': '/tools/buildbot/bin/python'} 22:14:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:14:36 INFO - mozversion application_buildid: 20160423193608 22:14:36 INFO - mozversion application_changeset: ab8a76ac7b34db20dd3c220c9846826334f3efdc 22:14:36 INFO - mozversion application_display_name: Nightly 22:14:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:14:36 INFO - mozversion application_name: Firefox 22:14:36 INFO - mozversion application_remotingname: firefox 22:14:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:14:36 INFO - mozversion application_vendor: Mozilla 22:14:36 INFO - mozversion application_version: 48.0a1 22:14:36 INFO - mozversion platform_buildid: 20160423193608 22:14:36 INFO - mozversion platform_changeset: ab8a76ac7b34db20dd3c220c9846826334f3efdc 22:14:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:14:36 INFO - mozversion platform_version: 48.0a1 22:14:36 INFO - using testdate: 1461474876 22:14:36 INFO - actual date: 1461474876 22:14:36 INFO - starting webserver on 'localhost:57393' 22:14:36 INFO - SUITE-START | Running 2 tests 22:14:36 INFO - TEST-START | dromaeo_css 22:14:36 INFO - operating with platform_type : linux_ 22:14:36 INFO - Initialising browser for dromaeo_css test... 22:14:36 INFO - TEST-INFO | started process 3897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzC6kxZ/profile http://localhost:57393/getInfo.html) 22:14:40 INFO - PROCESS | 3897 | 22:14:40 INFO - PROCESS | 3897 | (process:3965): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:14:41 INFO - PROCESS | 3897 | __metrics Screen width/height:1600/1200 22:14:41 INFO - PROCESS | 3897 | colorDepth:24 22:14:41 INFO - PROCESS | 3897 | Browser inner width/height: 1024/697 22:14:41 INFO - PROCESS | 3897 | __metrics 22:14:42 ERROR - PROCESS | 3897 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:14:42 INFO - TEST-INFO | 3897: exit 0 22:14:42 INFO - Browser initialized. 22:14:42 INFO - Running cycle 1/1 for dromaeo_css test... 22:14:42 INFO - TEST-INFO | started process 4016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzC6kxZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:44 INFO - PROCESS | 4016 | 22:14:44 INFO - PROCESS | 4016 | (process:4075): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:14:54 INFO - PROCESS | 4016 | RSS: Main: 164651008 22:14:54 INFO - PROCESS | 4016 | 22:15:44 INFO - PROCESS | 4016 | 1461474944281 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] 22:17:51 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57393/tests/dromaeo/cssquery-ext.html) 22:17:52 INFO - PROCESS | 4016 | RSS: Main: 169558016 22:17:52 INFO - PROCESS | 4016 | 22:20:54 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-ext.html (next: http://localhost:57393/tests/dromaeo/cssquery-jquery.html) 22:20:55 INFO - PROCESS | 4016 | RSS: Main: 167018496 22:20:55 INFO - PROCESS | 4016 | 22:23:56 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57393/tests/dromaeo/cssquery-mootools.html) 22:23:57 INFO - PROCESS | 4016 | RSS: Main: 167985152 22:23:57 INFO - PROCESS | 4016 | 22:26:59 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57393/tests/dromaeo/cssquery-prototype.html) 22:26:59 INFO - PROCESS | 4016 | RSS: Main: 165330944 22:26:59 INFO - PROCESS | 4016 | 22:30:02 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57393/tests/dromaeo/cssquery-yui.html) 22:30:02 INFO - PROCESS | 4016 | RSS: Main: 166744064 22:30:02 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | Cycle 1(1): loaded http://localhost:57393/tests/dromaeo/cssquery-yui.html (next: http://localhost:57393/tests/dromaeo/cssquery-dojo.html) 22:33:05 INFO - PROCESS | 4016 | RSS: Main: 168542208 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | __start_tp_report 22:33:05 INFO - PROCESS | 4016 | _x_x_mozilla_page_load 22:33:05 INFO - PROCESS | 4016 | _x_x_mozilla_page_load_details 22:33:05 INFO - PROCESS | 4016 | |i|pagename|runs| 22:33:05 INFO - PROCESS | 4016 | |0;dojo.html;3147.638021627513;3188.8405579721016;3217.420864244435;3252.967470325299;3253.9674603253975;5227.634065615403;5247.711375874325;5253.474652534749;5306.204069389593;5314.176302673082;5294.126469132589;5336.43967383424;5361.490658387447;5366.70483123428;5478.506934375905;4896.804127834882;4940.827071052514;4993;5012.9749351253195;5057.291979122926;663.7222700428977;4944.579710724574;4975.651704380694;4992.126377883874;5046.167382381903;4969.900601987958;4971.0803501352;4978.6514943953935;4997.250412438145;5023.14606516893;4897.314375987365;5054.646174767768;5085.262636917663;5089.727876921831;5089.949100508985;4883.902321953541;4884.951150488485;4885.804567817283;4923.852284431473;4948.084604348207;5261.789528418859;5266.894662106774;5285.233641122016;5303.443138470482;5322.574194064504;5627.465390787868;5712;5724.771009159628;5735.311762588516;5790.507806836424;5721.113227449752;5734.340550836666;5738.913916291217;5742.483176514133;5762.711864406763;5334.0665383557725;5338.172583249602;5339.946600533984;5342.732863356817;5358.276632654581;5749.8275051748515;5902.1146827976145;5910.261217347832;5961.791337303173;5984.132300816357;5598.636088654224;5602.1036634139155;5602.411746766612;5604.8038318658655;5605.775768969277;5661.688607126647;5661.801836935688;5662.66024038559;5663.773449061991;5665.716714164275;5595.440455954372;5750.1949727038245;5799.420057994167;5832.096025116114;5835.912461313083;5532.2531458253425;5580.693061881636;5615.719214039364;5645.774169033275;5692.14617807324;4274.10243848789;4274.935875961863;4286.742795432284;4308.6983911126545;4331.826726930887;3851.5763266040713;3882.96117038826;3923.9607603924164;3937.330653788863;3963.147923196469;5390.649607775482;5428.022955867903;5440.510354068074;5444.809431669952;5482.451754824486;5648.181013752983;5724.3130824300515;5736.713164341766;5758.3090029196765;5768.711564421762;5432.864178395572;5460.290162278879;5477.589180811455;5505.504504594605;5525.972370138204;4883.243097319921;4888.095702295087;4907.411110666742;4916;4945.530174633404;5788.073908174756;5846.386129456346;5864.648121112747;5866.882662346815;6006.369331220247;5702.230198923258;5704.743286552032;5719.8570035749435;5720.427957204246;5730.369659337386;5845.941540584539;5848.356680765028;5855.268091488564;5862.179294898803;5890.293164820249;5585.245991791218;5599.580031497572;5603.859903502445;5644.294463192101;5718;5676.886462270649;5709.971450142889;5731.19763233156;5760;5763.596548241583;5540.501354877919;5574.386817449996;5589.524890384363;5590.189422533628;5601.6358936668985;5365.678059316453;5567.582431317586;5642.492175704207;5709.371969083314;5728.656280623176;5309.283246761638;5508.944910551004;5580.441955804387;5584.078627026495;5669.971650141723;5511.972440137773;5517.44825517445;5553.222548843246;5555.22226888244;5576.86057848557;5408.621396502364;5446.618736688394;5450.1007333789485;5582.637128586629;5648.57635677334 22:33:05 INFO - PROCESS | 4016 | |1;ext.html;603.2730559675591;635.2504045226636;645.3256347117263;657.6120089147402;659.944089456869;30993.225169370748;37936.810315948416;38806.61193388065;38844.41733374001;39043.80478097609;40851.18297634053;41322.38016429756;41618.58381416185;41631.583684163146;41722.95692607686;8314.085450600429;8580.871286930702;8605.741827745178;8627.95686021569;8671.4363566368;8600;8686.652533898636;8687;8698;8883.200511953923;8892.155245251723;8945.329100317469;8975.416597921147;8983.10168983103;8994.055624159468;8848.823023539526;8899.555022248862;8915.063918288584;8916.955415222916;8925.018247992715;8612.267957223643;8766.60550275239;8787.516686582236;8806.823863522757;8828.470291782449;40818.591814081774;41302.1739565207;41429.1714165715;41527;41722.58277417217;3389.6440873708398;3466.272082862602;3581.301646178996;3736.3834967230373;3997.8600748973936;30658;31656.525152122736;31864.20339491508;31977.360452791047;32098.679013209803;3718.7396882218254;3719.7210209234145;3763.1156678180605;3801.505804245458;3805.6194380562;306217;308080;309026;309079;311765;35173.824130879184;36494.45258321127;56131.71934140303;56943.29170124903;57160.71419642875;34215.4867676985;34292;34293.314133717686;34339.656603434145;34357.48463773046;15047.097174169585;15223.162726050174;15319.46381876629;15342.232888355538;15355.692886142215;12717.427715752629;12786.36068196605;12794.744105117845;12807.871921280854;12811;8562.315014798925;8600.096989816104;8638.91361086381;8775.736727898173;8776.956115219511;3313.370459612714;3341.8779075287366;3382.780119292238;3420.6579342066093;3431.107911942913;1046.7487802927394;1115.2026301194685;1118.4184224203368;1162.738383863624;1202.1825158892038;27905.72094279072;28296.71703282982;29076.273093172418;29428.575703619605;29706.554401683992;1122.8764835868049;1142.817149256115;1174.0020982165092;1185.1497149355814;1241.1622155045309;15685.294161762748;15709.842901571066;15973.242943276231;16008.519744407686;16219.837801622069;481013;489969.25230607844;492330;492800;493706;57705.13442298285;58922.70538647279;59027.40972590305;59576.404235957954;59713.701431493435;1358.6807100331414;1383.8339399272152;1388.8333399992066;1392.6657602175405;1407.1908652524635;29350.266243344086;29493.26266843346;30417.695823042373;30683.693163068085;31044.701904429872;49813.501864980884;50669.49330506648;50947;50972.74513627408;51029.234561480866;438363;445245;446368;446961;448509;27705.861470693322;27802;27875.860620696767;28079.57880631833;28103.578446322914;47590.048199036515;48075.5192448071;48536.51463485461;49450.75274623748;49548;40213.39679904862;41355.379669304384;41887.162256755306;41983;42033.57966420297;40356.3946540808;40437.79781101194;41140.794296028325;41510.79244603758;41852.37221441742;41158;41246.38130427986;42293;42370.78814605907;42928.57071429246;29953.55069673913;32481.187970300933;35398.823005884806;35539.644603553636;36263.81868090643;27480.312992175357;27538.724612754417;27841.16476505708;27921.860390697915;27941.44117117687 22:33:05 INFO - PROCESS | 4016 | |2;jquery.html;379.62417206965114;382.7760759955426;384.3293452924646;391.65534329789796;393.5316972802363;17047.846148462526;18482.537936551595;18883.433496995094;19092.904535477337;19119.330823421184;17169.74245386317;17311.567210819736;17343;17581;19356.12897419616;5551.084071128259;5676.664374071751;5704.971475142618;5724;5729.971350143243;5458.181272809071;5665.943340566603;5678;5689.4879460848515;5698.629589076718;5555.611107222476;5615.943840561583;5630.436956304377;5635.549156067505;5644.181593668915;5477.096279113942;5613.635113717595;5613.691246981414;5621.915671264935;5644.7459864306;4856.684315519516;4942.802287908479;4963.205887058053;4990.25146228065;4998.975005124988;22380.88809555958;22592.322230333117;22991.310260692208;23116.884415577813;23182.42043948915;2396.101461951768;2403.6034054381007;2445.7921076708503;2465.4822487277515;2528.5575024370846;14046.087004344685;14319.928400358036;14559.854401455956;14616.342264598012;14701;1882.717592361143;1927.5277556998626;1946.6593346164364;1970.802919708032;1998.0708970328867;81097;82697.75953360765;83110.58444707737;83143.58428207881;84454.15545844524;10120.139788118093;10169.9491502542;10422.791544169075;10555.577776888838;10765.461726913623;14669.77995330071;14761.409543618134;14923.70152596942;14959.850401496064;14980;8192.262696357357;8291.129431409736;8393.580320984049;8511.74464766058;8574.185452382013;8272.17278272168;8294.419390642717;8297.336213102937;8304.750857474286;8330.583470826434;6363.649999249993;6413.807585772434;6511.218653761579;6664.900026499607;6704.195496540447;1855.1373611270824;1876.3151449720754;1926.3450426854936;1936.7966363531907;1937.1282922684848;929.3540989012571;963.6145541783343;1089.54239219528;1109.9611513596985;1151.441550847838;17118.828811711723;18704.81295187058;18810.341638042602;18892.433227003214;19260;1046.2571574182416;1055.904968552834;1064.8988346107108;1093.9945300273607;1097.204526718123;10846.511906964206;11042.227044106836;11070.39112848801;11183.44082795857;11220.382878941573;348979;355254;361727;473715;483406;88841.55579222192;94428.52785736027;95907;96962.51518742362;100785;1094.0919037199105;1118.1948996722347;1131.8754936956927;1133.8922802333766;1157.6179860645966;16958.74561881548;17230.827691722923;17242.913785430992;17276;17360;37578.62421375751;38101;38147.809260953516;38398.80800595979;40289;54673.45326546684;55516;56344.436555635555;56476;56544.71727641336;8165.142660020612;8198.959005204935;8270.297024752963;8378.078411374865;8524.74425767228;12111;13108.934455328044;13318.267495287851;13520;13520;11339.752627210835;11480.311181329147;11779;11944.820827687417;12073.551173858841;11506.94246528762;11629.360385178896;11676.182667213554;11810.645680629252;11878.109141814572;5860.501857342174;6744.763933262312;11940.641780746591;11995.40022998829;12112;7976.16250293734;8272.33821294307;8383.748487545383;8438.240558349778;8511.95744021276;11297.830532541853;12172.452239649414;12202.572909948109;12211;12334.444949977093 22:33:05 INFO - PROCESS | 4016 | |3;mootools.html;1377.249399077503;1444.3861358922456;1574.79527661404;1784.6966015777316;1827.4334956163582;7495;7933.722319718811;8025.117237103921;8297.66809327627;8309.210624990632;7944.523328600288;8053;8065.072516660586;8070.71752488663;8078.030636323635;2264.5810525052875;2298.184144628658;2301.5742087713743;2305.804006659436;2310.1106074161403;2275.1240772302617;2288.5079707862806;2299.022915261016;2311.8266130040315;2317.1774020222747;2286.4741109544816;2288.382136823057;2292.3925159832656;2308.595995700754;2308.6306191009444;2248.415411992877;2272.522770218256;2273.340731187962;2278.8746618936034;2280.680704701343;2202.658587918875;2214.202886960693;2216.2021672198;2218.9223377181884;2219.5449932763745;8112.837743245161;8130.3495720342235;8168.509889406595;8213.137620549816;8215.548144852031;4920.089783390048;5022.447530771612;5052.191649336089;5052.444231134572;5052.570531504826;3078.1381213260324;3083.691630836921;3185.3310804731036;3196.5205219217305;3213.2770126721534;1001.4727540500794;1008.7780688248573;1009.2834087797672;1023.7133602591286;1066.0458889294039;11209.103271738242;11221;11238.66284011481;11254.54981800727;11259.605913792979;4267.850625228164;4453.421055262795;4571;4579.95420045802;4598.931016034695;9546.331756776959;9563.904360956301;9569.61721531131;9640.276979226586;9650.324477286522;5126.794928202905;5153.304303918923;5164.870878227999;5181.5077567631015;5184.066867963803;4178.12259425518;4600.631949444103;4736.905261894743;4769.356136921471;4788.760561971957;3839.462475253467;3845.750026248285;3911.9608803912165;3917.490726205632;3976.6818654508147;1240.8510978682436;1241.6461308527028;1251.7496500699897;1255.5291765587904;1273.2296960339047;932.2588542108904;938.9061093890693;954.3653470442217;968.4141094637783;976.7167521418709;5568.916466253009;5665.745041473061;5722.942770572325;5876.559258055663;5940.138679891392;454.2096751652102;457.13145024453803;465.5786513205554;468.2811883758483;475.81681052794926;3985;4016.457778199964;4018.216447792711;4044.1102957349335;4056.776877271778;9092.181703646704;9599.136077753035;9750;9791.951040244754;9843.064908833649;9445.858312125323;9565.521723913776;9661.613535458644;9678.27412944032;9740.123388895034;7406.851862962711;7440.441966852508;7486.326230639269;7551.848963020819;7598.20218877021;5395.70323632204;5417.945820541901;5534.05920993424;5556.555475561864;5598.972005139948;8653.567321633767;8873.91126088731;8920.73237802867;8926.910730892867;8990.056044115274;9009.459432434074;9076.09239076087;9081.818363632558;9297.581608827484;9316.813663726824;6465.0948867157695;6877;6945.652717364213;7019.333163349474;7095.82260443493;7847.882281765664;7909.05091389037;7925.722599708983;7932.682692692228;7933.484323518953;6953.443724502129;7135.678894449658;7185.137783466018;7217.314355136254;7318.707251709872;7153.35619794221;7210.315020073084;7269.527480713737;7277.199508054216;7399;6503.252126005381;6631.867362652623;6633.568818026813;6639.402453779183;6643.534952553275;4882.7558622068045;4906.141425250496;4970.204767237225;5026.421961474477;5086.923696144635;5107.719075450737;5152.156972445889;5190.455002224863;5200.739963001759;5247.317848679647 22:33:05 INFO - PROCESS | 4016 | |4;prototype.html;321.9806811591304;327.97704160708753;331.3670888602769;343.81777657841354;344.8689497990763;6082;6128.632282063079;6136.693165341731;6169.845753856156;6174.104754810561;6008.819735407945;6041.275046994356;6042.093685947099;6048.939510604892;6068.726907289171;4196.580341965794;4426.269665505196;4549.476810166823;4566.97716511417;4570.565796249368;4565.589096981271;4588.839390621329;4595.540445955411;4595.632349412056;4605.746683932382;4539.568740969619;4546.909061818778;4549.385832912548;4550.931736023962;4572.885677858047;4254.361845723135;4491.079328737634;4500.392447019643;4556.157110934488;4569.383133276999;3861.073342397832;3861.575226725058;3868.9940615439905;3875.2443273561667;3880.3985382265796;5812.651240925516;5815.709214539256;5820.650760954356;5838.386969368197;5846.093855452412;5026.924596131061;5200.531952124289;5255.553277971377;5292.285541451894;5296.947030529684;6251.437370636664;6267.655278959655;6290.465310448617;6325.778597749057;6326.367363263683;4324.13517296542;4355.607995280408;4359.629431498326;4359.978200109011;4363.454568178978;6403.519736019817;6410.935890641127;6585.14393128895;6621.470282377399;6710.127683401224;4442.933355999663;4452.109578084396;4461.776911154429;4463.593967900101;4481.5070342262325;4460.598546130864;4496.865094047183;4503.88740281489;4516.706414083074;4518.615917647036;5168.483151684802;5184.403793563713;5253.527182553512;5264.499872512105;5274.841754747364;6374.9681251594375;6378.45783108441;6380.149756570635;6393.296737358887;6413.166288382574;1523.6876440329927;1529.4111766969797;1540.8998415102983;1551.6276093737647;1553.9456119035779;964.9131578158;1003.5734812704571;1006.6828948881225;1025.374521541859;1045.075108528942;6025.9397406026255;6034.818955431344;6046.758129674852;6046.848828779316;6049.243844519435;6484.773032943825;6531.477481801445;6533.1180290661105;6544.821932052277;6618.437432818265;5855.59010869235;5945.881082378328;5962.002729085718;5998.7300571473515;5999.520038396918;3445.9675485572534;3472.8610855566;3492.4935884296633;3507.859685612547;3524.7356448266482;6546.967265163644;6696.1629796275465;6708.2956289589865;6750.898736518956;6789.879669854418;6668.733250669919;6674.83312917181;6684.364985326386;6691.1636045494315;6702.195736511552;6552.770653027121;6693.933060669331;6730.293319201512;6739.090222819955;6758.526903116833;6469.611823290617;6488.188976377953;6524.771632993013;6544.3782840630065;6550.246721626883;6298.307186209422;6389.9552143313695;6392.712327945346;6412.743490260524;6415.775447859302;6688.899666504909;6696.732130714911;6697.564658297195;6751.45988320943;6763.797086087426;4364.912701745884;4367.519572846921;4367.563243675734;4378.824847006084;4386.298192289219;6256.968715156395;6400.807975760735;6453.612783233021;6471.676416179078;6498.155239818793;6238.500919926304;6251.843703907439;6285.968570157303;6295.811125666238;6301.8109456716375;6126.877462450815;6221.502279817694;6225.782097626561;6287.119803227461;6305.779297724557;6116.724747386467;6156.78451254204;6181.567290289817;6205.565610407228;6226.937730622636;6155.538334624832;6282.371762823681;6305.936940630535;6312.400321969406;6346.650934198663;6121.0818377241985;6199.876002479835;6206.69659371519;6218.833381634407;6247.812565623039 22:33:05 INFO - PROCESS | 4016 | |5;yui.html;710.6198183971576;769.5267410542516;790.4545863354289;822.2024636102982;824.4805772363412;13661.726765464697;13902.374393152308;13941.790873136912;13966.790498142524;13976;14563;14919.92540037301;14940.178290194035;14943.850561494382;15116.697666046672;693.2339764560139;814.6863457568849;836.297510091523;840.9866111335856;842.1157784326433;842.692417267699;846.1158089796178;847.4364547575885;849.4393700157897;851.2423942691009;878.6573236437795;880.7754022724229;883.8850949376572;884.3411658314565;885.4554449013842;867.6963062927956;871.7820544863815;873.9344549158775;874.1695389380051;874.396666300254;633.2179757998867;636.4590098416355;636.7389370358177;639.5619000984341;641.9454346380563;15368.769468457982;15391.692166156727;15424.922875385664;15428.845711542854;15435.536933891888;13203.603891883258;13346.86653133466;13350.465981360734;13364.532241371506;13406.411458437306;10544.261901666887;10706.892931070668;10710.625128120557;10715.732106697395;10844.67465976022;3712.7029837612927;3716.1452865841006;3775.8867233983024;3784.394496880486;3813.046738315421;63533.682331588505;63720.362796371905;64294.357056429304;64617.67691161514;64835.99999999953;10794.24440289188;10944.17918656104;10972.396518191417;11036.889631103746;11080.667579972614;17322.82677173212;17712.557186070193;17731.113444327733;17737;20346.694799578017;8066.596670166468;8135.3898457615915;8321.708740194064;8352.33181345503;8370.916290837136;4571.3600095986585;4599.471060827981;4654.255319148988;4711.340412342275;4735.479097299294;3187.7449804015623;3211.7430605551967;3219.3400352927592;3232.919177020546;3249.1877030742316;600.5555888642373;604.2567641800522;611.1993288791697;616.488314698799;619.1672200889795;613.8680183760424;631.1006815288288;650.3261514133209;659.8482349059647;668.9397954184146;8702.738917832476;8835.160659737314;8919.108089191159;9010;9053.321000924958;629.7953165221321;635.0996423854876;685.8456847209338;723.7105157936762;761.9047619047619;4152.8131234094535;4263.33344897825;4298.591633794785;4311.461067366579;4360.258756011485;62848.68575657184;63850.36149638537;64166;64281;65736.34263657397;56446;57418.425815741306;57684.42315576791;58004;58018.709906450196;48339.75830120827;48420.27369589459;48567;51018.23472647913;52366.738166308925;8222.62998165072;8305;8317.376196785144;8417.663293468193;8548.829023419621;33647.663523364456;34310.48534271938;34417.655823442445;34530;35383;45557.77221114006;46721.29918051164;47814.28278575755;47987.520124798306;48501.27248091352;10110.545025473724;10429.687109386427;11618.128640351839;11650.475728592064;11735.41322933867;13020.218786872818;13257.602271931857;13528.79706804379;13729.382177802223;13926.094803837717;12997;13217.669558261121;13345.933270333975;13465.259410732504;13569.1858488491;12737.29944853042;13125.671858203239;13224.801627975781;13415.48997844892;13665.385057672229;11153;12815.487380504675;13089.149205301625;13174.077814553273;13176.275304858325;10130.949345253226;10324.22568307365;10905.945470272914;11237.269577477438;11357.261777984735;10386.948065259625;10560.84158737635;10619.522121504397;10781.568737250736;10786.244962852525 22:33:05 INFO - PROCESS | 4016 | __end_tp_report 22:33:05 INFO - PROCESS | 4016 | __start_cc_report 22:33:05 INFO - PROCESS | 4016 | _x_x_mozilla_cycle_collect,348 22:33:05 INFO - PROCESS | 4016 | __end_cc_report 22:33:05 INFO - PROCESS | 4016 | __startTimestamp1461475985458__endTimestamp 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | ------- Summary: start ------- 22:33:05 INFO - PROCESS | 4016 | Number of tests: 6 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#0] dojo.html Cycles:165 Average:5298.62 Median:5532.25 stddev:682.61 (12.3%) stddev-sans-first:663.51 22:33:05 INFO - PROCESS | 4016 | Values: 3147.6 3188.8 3217.4 3253.0 3254.0 5227.6 5247.7 5253.5 5306.2 5314.2 5294.1 5336.4 5361.5 5366.7 5478.5 4896.8 4940.8 4993.0 5013.0 5057.3 663.7 4944.6 4975.7 4992.1 5046.2 4969.9 4971.1 4978.7 4997.3 5023.1 4897.3 5054.6 5085.3 5089.7 5089.9 4883.9 4885.0 4885.8 4923.9 4948.1 5261.8 5266.9 5285.2 5303.4 5322.6 5627.5 5712.0 5724.8 5735.3 5790.5 5721.1 5734.3 5738.9 5742.5 5762.7 5334.1 5338.2 5339.9 5342.7 5358.3 5749.8 5902.1 5910.3 5961.8 5984.1 5598.6 5602.1 5602.4 5604.8 5605.8 5661.7 5661.8 5662.7 5663.8 5665.7 5595.4 5750.2 5799.4 5832.1 5835.9 5532.3 5580.7 5615.7 5645.8 5692.1 4274.1 4274.9 4286.7 4308.7 4331.8 3851.6 3883.0 3924.0 3937.3 3963.1 5390.6 5428.0 5440.5 5444.8 5482.5 5648.2 5724.3 5736.7 5758.3 5768.7 5432.9 5460.3 5477.6 5505.5 5526.0 4883.2 4888.1 4907.4 4916.0 4945.5 5788.1 5846.4 5864.6 5866.9 6006.4 5702.2 5704.7 5719.9 5720.4 5730.4 5845.9 5848.4 5855.3 5862.2 5890.3 5585.2 5599.6 5603.9 5644.3 5718.0 5676.9 5710.0 5731.2 5760.0 5763.6 5540.5 5574.4 5589.5 5590.2 5601.6 5365.7 5567.6 5642.5 5709.4 5728.7 5309.3 5508.9 5580.4 5584.1 5670.0 5512.0 5517.4 5553.2 5555.2 5576.9 5408.6 5446.6 5450.1 5582.6 5648.6 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#1] ext.html Cycles:180 Average:56146.98 Median:21520.83 stddev:111956.92 (520.2%) stddev-sans-first:112193.32 22:33:05 INFO - PROCESS | 4016 | Values: 603.3 635.3 645.3 657.6 659.9 30993.2 37936.8 38806.6 38844.4 39043.8 40851.2 41322.4 41618.6 41631.6 41723.0 8314.1 8580.9 8605.7 8628.0 8671.4 8600.0 8686.7 8687.0 8698.0 8883.2 8892.2 8945.3 8975.4 8983.1 8994.1 8848.8 8899.6 8915.1 8917.0 8925.0 8612.3 8766.6 8787.5 8806.8 8828.5 40818.6 41302.2 41429.2 41527.0 41722.6 3389.6 3466.3 3581.3 3736.4 3997.9 30658.0 31656.5 31864.2 31977.4 32098.7 3718.7 3719.7 3763.1 3801.5 3805.6 306217.0 308080.0 309026.0 309079.0 311765.0 35173.8 36494.5 56131.7 56943.3 57160.7 34215.5 34292.0 34293.3 34339.7 34357.5 15047.1 15223.2 15319.5 15342.2 15355.7 12717.4 12786.4 12794.7 12807.9 12811.0 8562.3 8600.1 8638.9 8775.7 8777.0 3313.4 3341.9 3382.8 3420.7 3431.1 1046.7 1115.2 1118.4 1162.7 1202.2 27905.7 28296.7 29076.3 29428.6 29706.6 1122.9 1142.8 1174.0 1185.1 1241.2 15685.3 15709.8 15973.2 16008.5 16219.8 481013.0 489969.3 492330.0 492800.0 493706.0 57705.1 58922.7 59027.4 59576.4 59713.7 1358.7 1383.8 1388.8 1392.7 1407.2 29350.3 29493.3 30417.7 30683.7 31044.7 49813.5 50669.5 50947.0 50972.7 51029.2 438363.0 445245.0 446368.0 446961.0 448509.0 27705.9 27802.0 27875.9 28079.6 28103.6 47590.0 48075.5 48536.5 49450.8 49548.0 40213.4 41355.4 41887.2 41983.0 42033.6 40356.4 40437.8 41140.8 41510.8 41852.4 41158.0 41246.4 42293.0 42370.8 42928.6 29953.6 32481.2 35398.8 35539.6 36263.8 27480.3 27538.7 27841.2 27921.9 27941.4 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#2] jquery.html Cycles:180 Average:26627.09 Median:23149.65 stddev:68120.22 (294.3%) stddev-sans-first:68282.81 22:33:05 INFO - PROCESS | 4016 | Values: 379.6 382.8 384.3 391.7 393.5 17047.8 18482.5 18883.4 19092.9 19119.3 17169.7 17311.6 17343.0 17581.0 19356.1 5551.1 5676.7 5705.0 5724.0 5730.0 5458.2 5665.9 5678.0 5689.5 5698.6 5555.6 5615.9 5630.4 5635.5 5644.2 5477.1 5613.6 5613.7 5621.9 5644.7 4856.7 4942.8 4963.2 4990.3 4999.0 22380.9 22592.3 22991.3 23116.9 23182.4 2396.1 2403.6 2445.8 2465.5 2528.6 14046.1 14319.9 14559.9 14616.3 14701.0 1882.7 1927.5 1946.7 1970.8 1998.1 81097.0 82697.8 83110.6 83143.6 84454.2 10120.1 10169.9 10422.8 10555.6 10765.5 14669.8 14761.4 14923.7 14959.9 14980.0 8192.3 8291.1 8393.6 8511.7 8574.2 8272.2 8294.4 8297.3 8304.8 8330.6 6363.6 6413.8 6511.2 6664.9 6704.2 1855.1 1876.3 1926.3 1936.8 1937.1 929.4 963.6 1089.5 1110.0 1151.4 17118.8 18704.8 18810.3 18892.4 19260.0 1046.3 1055.9 1064.9 1094.0 1097.2 10846.5 11042.2 11070.4 11183.4 11220.4 348979.0 355254.0 361727.0 473715.0 483406.0 88841.6 94428.5 95907.0 96962.5 100785.0 1094.1 1118.2 1131.9 1133.9 1157.6 16958.7 17230.8 17242.9 17276.0 17360.0 37578.6 38101.0 38147.8 38398.8 40289.0 54673.5 55516.0 56344.4 56476.0 56544.7 8165.1 8199.0 8270.3 8378.1 8524.7 12111.0 13108.9 13318.3 13520.0 13520.0 11339.8 11480.3 11779.0 11944.8 12073.6 11506.9 11629.4 11676.2 11810.6 11878.1 5860.5 6744.8 11940.6 11995.4 12112.0 7976.2 8272.3 8383.7 8438.2 8512.0 11297.8 12172.5 12202.6 12211.0 12334.4 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#3] mootools.html Cycles:180 Average:5391.19 Median:5182.79 stddev:2948.19 (56.9%) stddev-sans-first:2941.03 22:33:05 INFO - PROCESS | 4016 | Values: 1377.2 1444.4 1574.8 1784.7 1827.4 7495.0 7933.7 8025.1 8297.7 8309.2 7944.5 8053.0 8065.1 8070.7 8078.0 2264.6 2298.2 2301.6 2305.8 2310.1 2275.1 2288.5 2299.0 2311.8 2317.2 2286.5 2288.4 2292.4 2308.6 2308.6 2248.4 2272.5 2273.3 2278.9 2280.7 2202.7 2214.2 2216.2 2218.9 2219.5 8112.8 8130.3 8168.5 8213.1 8215.5 4920.1 5022.4 5052.2 5052.4 5052.6 3078.1 3083.7 3185.3 3196.5 3213.3 1001.5 1008.8 1009.3 1023.7 1066.0 11209.1 11221.0 11238.7 11254.5 11259.6 4267.9 4453.4 4571.0 4580.0 4598.9 9546.3 9563.9 9569.6 9640.3 9650.3 5126.8 5153.3 5164.9 5181.5 5184.1 4178.1 4600.6 4736.9 4769.4 4788.8 3839.5 3845.8 3912.0 3917.5 3976.7 1240.9 1241.6 1251.7 1255.5 1273.2 932.3 938.9 954.4 968.4 976.7 5568.9 5665.7 5722.9 5876.6 5940.1 454.2 457.1 465.6 468.3 475.8 3985.0 4016.5 4018.2 4044.1 4056.8 9092.2 9599.1 9750.0 9792.0 9843.1 9445.9 9565.5 9661.6 9678.3 9740.1 7406.9 7440.4 7486.3 7551.8 7598.2 5395.7 5417.9 5534.1 5556.6 5599.0 8653.6 8873.9 8920.7 8926.9 8990.1 9009.5 9076.1 9081.8 9297.6 9316.8 6465.1 6877.0 6945.7 7019.3 7095.8 7847.9 7909.1 7925.7 7932.7 7933.5 6953.4 7135.7 7185.1 7217.3 7318.7 7153.4 7210.3 7269.5 7277.2 7399.0 6503.3 6631.9 6633.6 6639.4 6643.5 4882.8 4906.1 4970.2 5026.4 5086.9 5107.7 5152.2 5190.5 5200.7 5247.3 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#4] prototype.html Cycles:180 Average:5263.72 Median:6047.89 stddev:1603.22 (26.5%) stddev-sans-first:1564.23 22:33:05 INFO - PROCESS | 4016 | Values: 322.0 328.0 331.4 343.8 344.9 6082.0 6128.6 6136.7 6169.8 6174.1 6008.8 6041.3 6042.1 6048.9 6068.7 4196.6 4426.3 4549.5 4567.0 4570.6 4565.6 4588.8 4595.5 4595.6 4605.7 4539.6 4546.9 4549.4 4550.9 4572.9 4254.4 4491.1 4500.4 4556.2 4569.4 3861.1 3861.6 3869.0 3875.2 3880.4 5812.7 5815.7 5820.7 5838.4 5846.1 5026.9 5200.5 5255.6 5292.3 5296.9 6251.4 6267.7 6290.5 6325.8 6326.4 4324.1 4355.6 4359.6 4360.0 4363.5 6403.5 6410.9 6585.1 6621.5 6710.1 4442.9 4452.1 4461.8 4463.6 4481.5 4460.6 4496.9 4503.9 4516.7 4518.6 5168.5 5184.4 5253.5 5264.5 5274.8 6375.0 6378.5 6380.1 6393.3 6413.2 1523.7 1529.4 1540.9 1551.6 1553.9 964.9 1003.6 1006.7 1025.4 1045.1 6025.9 6034.8 6046.8 6046.8 6049.2 6484.8 6531.5 6533.1 6544.8 6618.4 5855.6 5945.9 5962.0 5998.7 5999.5 3446.0 3472.9 3492.5 3507.9 3524.7 6547.0 6696.2 6708.3 6750.9 6789.9 6668.7 6674.8 6684.4 6691.2 6702.2 6552.8 6693.9 6730.3 6739.1 6758.5 6469.6 6488.2 6524.8 6544.4 6550.2 6298.3 6390.0 6392.7 6412.7 6415.8 6688.9 6696.7 6697.6 6751.5 6763.8 4364.9 4367.5 4367.6 4378.8 4386.3 6257.0 6400.8 6453.6 6471.7 6498.2 6238.5 6251.8 6286.0 6295.8 6301.8 6126.9 6221.5 6225.8 6287.1 6305.8 6116.7 6156.8 6181.6 6205.6 6226.9 6155.5 6282.4 6305.9 6312.4 6346.7 6121.1 6199.9 6206.7 6218.8 6247.8 22:33:05 INFO - PROCESS | 4016 | 22:33:05 INFO - PROCESS | 4016 | [#5] yui.html Cycles:180 Average:15228.66 Median:25064.57 stddev:18184.78 (72.6%) stddev-sans-first:18203.11 22:33:05 INFO - PROCESS | 4016 | Values: 710.6 769.5 790.5 822.2 824.5 13661.7 13902.4 13941.8 13966.8 13976.0 14563.0 14919.9 14940.2 14943.9 15116.7 693.2 814.7 836.3 841.0 842.1 842.7 846.1 847.4 849.4 851.2 878.7 880.8 883.9 884.3 885.5 867.7 871.8 873.9 874.2 874.4 633.2 636.5 636.7 639.6 641.9 15368.8 15391.7 15424.9 15428.8 15435.5 13203.6 13346.9 13350.5 13364.5 13406.4 10544.3 10706.9 10710.6 10715.7 10844.7 3712.7 3716.1 3775.9 3784.4 3813.0 63533.7 63720.4 64294.4 64617.7 64836.0 10794.2 10944.2 10972.4 11036.9 11080.7 17322.8 17712.6 17731.1 17737.0 20346.7 8066.6 8135.4 8321.7 8352.3 8370.9 4571.4 4599.5 4654.3 4711.3 4735.5 3187.7 3211.7 3219.3 3232.9 3249.2 600.6 604.3 611.2 616.5 619.2 613.9 631.1 650.3 659.8 668.9 8702.7 8835.2 8919.1 9010.0 9053.3 629.8 635.1 685.8 723.7 761.9 4152.8 4263.3 4298.6 4311.5 4360.3 62848.7 63850.4 64166.0 64281.0 65736.3 56446.0 57418.4 57684.4 58004.0 58018.7 48339.8 48420.3 48567.0 51018.2 52366.7 8222.6 8305.0 8317.4 8417.7 8548.8 33647.7 34310.5 34417.7 34530.0 35383.0 45557.8 46721.3 47814.3 47987.5 48501.3 10110.5 10429.7 11618.1 11650.5 11735.4 13020.2 13257.6 13528.8 13729.4 13926.1 12997.0 13217.7 13345.9 13465.3 13569.2 12737.3 13125.7 13224.8 13415.5 13665.4 11153.0 12815.5 13089.1 13174.1 13176.3 10130.9 10324.2 10905.9 11237.3 11357.3 10386.9 10560.8 10619.5 10781.6 10786.2 22:33:05 INFO - PROCESS | 4016 | -------- Summary: end -------- 22:33:05 INFO - PROCESS | 4016 | 22:33:06 INFO - TEST-INFO | 4016: exit 0 22:33:06 INFO - TEST-OK | dromaeo_css | took 1109757ms 22:33:06 INFO - TEST-START | kraken 22:33:06 INFO - operating with platform_type : linux_ 22:33:06 INFO - Initialising browser for kraken test... 22:33:06 INFO - TEST-INFO | started process 4249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVCvaM_/profile http://localhost:57393/getInfo.html) 22:33:09 INFO - PROCESS | 4249 | 22:33:09 INFO - PROCESS | 4249 | (process:4317): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:33:10 INFO - PROCESS | 4249 | __metrics Screen width/height:1600/1200 22:33:10 INFO - PROCESS | 4249 | colorDepth:24 22:33:10 INFO - PROCESS | 4249 | Browser inner width/height: 1024/697 22:33:10 INFO - PROCESS | 4249 | __metrics 22:33:11 ERROR - PROCESS | 4249 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 22:33:12 INFO - TEST-INFO | 4249: exit 0 22:33:12 INFO - Browser initialized. 22:33:12 INFO - Running cycle 1/1 for kraken test... 22:33:12 INFO - TEST-INFO | started process 4368 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVCvaM_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:14 INFO - PROCESS | 4368 | 22:33:14 INFO - PROCESS | 4368 | (process:4429): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:33:24 INFO - PROCESS | 4368 | RSS: Main: 164446208 22:33:24 INFO - PROCESS | 4368 | 22:33:26 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-beat-detection) 22:33:27 INFO - PROCESS | 4368 | RSS: Main: 169476096 22:33:27 INFO - PROCESS | 4368 | 22:33:30 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-dft) 22:33:30 INFO - PROCESS | 4368 | RSS: Main: 173436928 22:33:30 INFO - PROCESS | 4368 | 22:33:33 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-fft) 22:33:34 INFO - PROCESS | 4368 | RSS: Main: 164347904 22:33:34 INFO - PROCESS | 4368 | 22:33:36 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57393/tests/kraken/driver.html?testName=audio-oscillator) 22:33:37 INFO - PROCESS | 4368 | RSS: Main: 164093952 22:33:37 INFO - PROCESS | 4368 | 22:33:39 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:33:39 INFO - PROCESS | 4368 | RSS: Main: 164548608 22:33:39 INFO - PROCESS | 4368 | 22:33:43 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-darkroom) 22:33:43 INFO - PROCESS | 4368 | RSS: Main: 164982784 22:33:43 INFO - PROCESS | 4368 | 22:33:47 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57393/tests/kraken/driver.html?testName=imaging-desaturate) 22:33:47 INFO - PROCESS | 4368 | RSS: Main: 167415808 22:33:47 INFO - PROCESS | 4368 | 22:33:50 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57393/tests/kraken/driver.html?testName=json-parse-financial) 22:33:51 INFO - PROCESS | 4368 | RSS: Main: 167272448 22:33:51 INFO - PROCESS | 4368 | 22:33:52 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57393/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:33:53 INFO - PROCESS | 4368 | RSS: Main: 167546880 22:33:53 INFO - PROCESS | 4368 | 22:33:55 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:33:55 INFO - PROCESS | 4368 | RSS: Main: 167034880 22:33:55 INFO - PROCESS | 4368 | 22:33:57 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:33:58 INFO - PROCESS | 4368 | RSS: Main: 167239680 22:33:58 INFO - PROCESS | 4368 | 22:34:01 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:34:01 INFO - PROCESS | 4368 | RSS: Main: 167804928 22:34:01 INFO - PROCESS | 4368 | 22:34:04 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:34:05 INFO - PROCESS | 4368 | RSS: Main: 167624704 22:34:05 INFO - PROCESS | 4368 | 22:34:06 INFO - PROCESS | 4368 | Cycle 1(1): loaded http://localhost:57393/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57393/tests/kraken/driver.html?testName=ai-astar) 22:34:07 INFO - PROCESS | 4368 | RSS: Main: 169168896 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | __start_tp_report 22:34:07 INFO - PROCESS | 4368 | _x_x_mozilla_page_load 22:34:07 INFO - PROCESS | 4368 | _x_x_mozilla_page_load_details 22:34:07 INFO - PROCESS | 4368 | |i|pagename|runs| 22:34:07 INFO - PROCESS | 4368 | |0;ai-astar;112;106;111;113;111;125;114;113;112;112 22:34:07 INFO - PROCESS | 4368 | |1;audio-beat-detection;152;128;157;178;130;157;150;130;179;127 22:34:07 INFO - PROCESS | 4368 | |2;audio-dft;163;163;162;162;164;164;173;166;164;162 22:34:07 INFO - PROCESS | 4368 | |3;audio-fft;75;76;74;159;77;72;76;77;165;77 22:34:07 INFO - PROCESS | 4368 | |4;audio-oscillator;93;92;153;95;92;93;147;93;92;145 22:34:07 INFO - PROCESS | 4368 | |5;imaging-gaussian-blur;118;118;118;117;117;117;117;118;119;117 22:34:07 INFO - PROCESS | 4368 | |6;imaging-darkroom;147;146;148;147;146;146;146;146;146;147 22:34:07 INFO - PROCESS | 4368 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;96 22:34:07 INFO - PROCESS | 4368 | |8;json-parse-financial;65;57;58;58;57;60;62;78;59;61 22:34:07 INFO - PROCESS | 4368 | |9;json-stringify-tinderbox;62;63;63;62;67;63;62;62;63;63 22:34:07 INFO - PROCESS | 4368 | |10;stanford-crypto-aes;85;77;77;78;78;77;79;79;79;79 22:34:07 INFO - PROCESS | 4368 | |11;stanford-crypto-ccm;128;128;128;136;126;132;129;126;159;134 22:34:07 INFO - PROCESS | 4368 | |12;stanford-crypto-pbkdf2;170;167;168;175;175;175;164;173;164;171 22:34:07 INFO - PROCESS | 4368 | |13;stanford-crypto-sha256-iterative;68;69;69;69;67;69;66;70;67;69 22:34:07 INFO - PROCESS | 4368 | __end_tp_report 22:34:07 INFO - PROCESS | 4368 | __start_cc_report 22:34:07 INFO - PROCESS | 4368 | _x_x_mozilla_cycle_collect,834 22:34:07 INFO - PROCESS | 4368 | __end_cc_report 22:34:07 INFO - PROCESS | 4368 | __startTimestamp1461476047080__endTimestamp 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | ------- Summary: start ------- 22:34:07 INFO - PROCESS | 4368 | Number of tests: 14 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#0] ai-astar Cycles:10 Average:112.90 Median:112.50 stddev:4.77 (4.2%) stddev-sans-first:5.05 22:34:07 INFO - PROCESS | 4368 | Values: 112.0 106.0 111.0 113.0 111.0 125.0 114.0 113.0 112.0 112.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#1] audio-beat-detection Cycles:10 Average:148.80 Median:154.50 stddev:19.79 (12.8%) stddev-sans-first:20.96 22:34:07 INFO - PROCESS | 4368 | Values: 152.0 128.0 157.0 178.0 130.0 157.0 150.0 130.0 179.0 127.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:3.30 (2.0%) stddev-sans-first:3.47 22:34:07 INFO - PROCESS | 4368 | Values: 163.0 163.0 162.0 162.0 164.0 164.0 173.0 166.0 164.0 162.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#3] audio-fft Cycles:10 Average:92.80 Median:76.00 stddev:36.53 (48.1%) stddev-sans-first:38.18 22:34:07 INFO - PROCESS | 4368 | Values: 75.0 76.0 74.0 159.0 77.0 72.0 76.0 77.0 165.0 77.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:92.50 stddev:26.88 (29.1%) stddev-sans-first:27.84 22:34:07 INFO - PROCESS | 4368 | Values: 93.0 92.0 153.0 95.0 92.0 93.0 147.0 93.0 92.0 145.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 22:34:07 INFO - PROCESS | 4368 | Values: 118.0 118.0 118.0 117.0 117.0 117.0 117.0 118.0 119.0 117.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 22:34:07 INFO - PROCESS | 4368 | Values: 147.0 146.0 148.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:34:07 INFO - PROCESS | 4368 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:60.50 stddev:6.31 (10.4%) stddev-sans-first:6.57 22:34:07 INFO - PROCESS | 4368 | Values: 65.0 57.0 58.0 58.0 57.0 60.0 62.0 78.0 59.0 61.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:1.49 (2.4%) stddev-sans-first:1.54 22:34:07 INFO - PROCESS | 4368 | Values: 62.0 63.0 63.0 62.0 67.0 63.0 62.0 62.0 63.0 63.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.00 stddev:2.35 (3.0%) stddev-sans-first:0.93 22:34:07 INFO - PROCESS | 4368 | Values: 85.0 77.0 77.0 78.0 78.0 77.0 79.0 79.0 79.0 79.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:130.50 stddev:9.86 (7.6%) stddev-sans-first:10.31 22:34:07 INFO - PROCESS | 4368 | Values: 128.0 128.0 128.0 136.0 126.0 132.0 129.0 126.0 159.0 134.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:172.00 stddev:4.34 (2.5%) stddev-sans-first:4.60 22:34:07 INFO - PROCESS | 4368 | Values: 170.0 167.0 168.0 175.0 175.0 175.0 164.0 173.0 164.0 171.0 22:34:07 INFO - PROCESS | 4368 | 22:34:07 INFO - PROCESS | 4368 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.25 (1.8%) stddev-sans-first:1.32 22:34:07 INFO - PROCESS | 4368 | Values: 68.0 69.0 69.0 69.0 67.0 69.0 66.0 70.0 67.0 69.0 22:34:07 INFO - PROCESS | 4368 | -------- Summary: end -------- 22:34:07 INFO - PROCESS | 4368 | 22:34:08 INFO - TEST-INFO | 4368: exit 0 22:34:08 INFO - TEST-OK | kraken | took 61895ms 22:34:08 INFO - SUITE-END | took 1171s 22:34:08 INFO - Completed test suite (00:19:31) 22:34:08 INFO - javascript benchmark 22:34:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.638021627513, 3188.8405579721016, 3217.420864244435, 3252.967470325299, 3253.9674603253975, 5227.634065615403, 5247.711375874325, 5253.474652534749, 5306.204069389593, 5314.176302673082, 5294.126469132589, 5336.43967383424, 5361.490658387447, 5366.70483123428, 5478.506934375905, 4896.804127834882, 4940.827071052514, 4993.0, 5012.9749351253195, 5057.291979122926, 663.7222700428977, 4944.579710724574, 4975.651704380694, 4992.126377883874, 5046.167382381903, 4969.900601987958, 4971.0803501352, 4978.6514943953935, 4997.250412438145, 5023.14606516893, 4897.314375987365, 5054.646174767768, 5085.262636917663, 5089.727876921831, 5089.949100508985, 4883.902321953541, 4884.951150488485, 4885.804567817283, 4923.852284431473, 4948.084604348207, 5261.789528418859, 5266.894662106774, 5285.233641122016, 5303.443138470482, 5322.574194064504, 5627.465390787868, 5712.0, 5724.771009159628, 5735.311762588516, 5790.507806836424, 5721.113227449752, 5734.340550836666, 5738.913916291217, 5742.483176514133, 5762.711864406763, 5334.0665383557725, 5338.172583249602, 5339.946600533984, 5342.732863356817, 5358.276632654581, 5749.8275051748515, 5902.1146827976145, 5910.261217347832, 5961.791337303173, 5984.132300816357, 5598.636088654224, 5602.1036634139155, 5602.411746766612, 5604.8038318658655, 5605.775768969277, 5661.688607126647, 5661.801836935688, 5662.66024038559, 5663.773449061991, 5665.716714164275, 5595.440455954372, 5750.1949727038245, 5799.420057994167, 5832.096025116114, 5835.912461313083, 5532.2531458253425, 5580.693061881636, 5615.719214039364, 5645.774169033275, 5692.14617807324, 4274.10243848789, 4274.935875961863, 4286.742795432284, 4308.6983911126545, 4331.826726930887, 3851.5763266040713, 3882.96117038826, 3923.9607603924164, 3937.330653788863, 3963.147923196469, 5390.649607775482, 5428.022955867903, 5440.510354068074, 5444.809431669952, 5482.451754824486, 5648.181013752983, 5724.3130824300515, 5736.713164341766, 5758.3090029196765, 5768.711564421762, 5432.864178395572, 5460.290162278879, 5477.589180811455, 5505.504504594605, 5525.972370138204, 4883.243097319921, 4888.095702295087, 4907.411110666742, 4916.0, 4945.530174633404, 5788.073908174756, 5846.386129456346, 5864.648121112747, 5866.882662346815, 6006.369331220247, 5702.230198923258, 5704.743286552032, 5719.8570035749435, 5720.427957204246, 5730.369659337386, 5845.941540584539, 5848.356680765028, 5855.268091488564, 5862.179294898803, 5890.293164820249, 5585.245991791218, 5599.580031497572, 5603.859903502445, 5644.294463192101, 5718.0, 5676.886462270649, 5709.971450142889, 5731.19763233156, 5760.0, 5763.596548241583, 5540.501354877919, 5574.386817449996, 5589.524890384363, 5590.189422533628, 5601.6358936668985, 5365.678059316453, 5567.582431317586, 5642.492175704207, 5709.371969083314, 5728.656280623176, 5309.283246761638, 5508.944910551004, 5580.441955804387, 5584.078627026495, 5669.971650141723, 5511.972440137773, 5517.44825517445, 5553.222548843246, 5555.22226888244, 5576.86057848557, 5408.621396502364, 5446.618736688394, 5450.1007333789485, 5582.637128586629, 5648.57635677334], "value": 5257.284963741046, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.2730559675591, 635.2504045226636, 645.3256347117263, 657.6120089147402, 659.944089456869, 30993.225169370748, 37936.810315948416, 38806.61193388065, 38844.41733374001, 39043.80478097609, 40851.18297634053, 41322.38016429756, 41618.58381416185, 41631.583684163146, 41722.95692607686, 8314.085450600429, 8580.871286930702, 8605.741827745178, 8627.95686021569, 8671.4363566368, 8600.0, 8686.652533898636, 8687.0, 8698.0, 8883.200511953923, 8892.155245251723, 8945.329100317469, 8975.416597921147, 8983.10168983103, 8994.055624159468, 8848.823023539526, 8899.555022248862, 8915.063918288584, 8916.955415222916, 8925.018247992715, 8612.267957223643, 8766.60550275239, 8787.516686582236, 8806.823863522757, 8828.470291782449, 40818.591814081774, 41302.1739565207, 41429.1714165715, 41527.0, 41722.58277417217, 3389.6440873708398, 3466.272082862602, 3581.301646178996, 3736.3834967230373, 3997.8600748973936, 30658.0, 31656.525152122736, 31864.20339491508, 31977.360452791047, 32098.679013209803, 3718.7396882218254, 3719.7210209234145, 3763.1156678180605, 3801.505804245458, 3805.6194380562, 306217.0, 308080.0, 309026.0, 309079.0, 311765.0, 35173.824130879184, 36494.45258321127, 56131.71934140303, 56943.29170124903, 57160.71419642875, 34215.4867676985, 34292.0, 34293.314133717686, 34339.656603434145, 34357.48463773046, 15047.097174169585, 15223.162726050174, 15319.46381876629, 15342.232888355538, 15355.692886142215, 12717.427715752629, 12786.36068196605, 12794.744105117845, 12807.871921280854, 12811.0, 8562.315014798925, 8600.096989816104, 8638.91361086381, 8775.736727898173, 8776.956115219511, 3313.370459612714, 3341.8779075287366, 3382.780119292238, 3420.6579342066093, 3431.107911942913, 1046.7487802927394, 1115.2026301194685, 1118.4184224203368, 1162.738383863624, 1202.1825158892038, 27905.72094279072, 28296.71703282982, 29076.273093172418, 29428.575703619605, 29706.554401683992, 1122.8764835868049, 1142.817149256115, 1174.0020982165092, 1185.1497149355814, 1241.1622155045309, 15685.294161762748, 15709.842901571066, 15973.242943276231, 16008.519744407686, 16219.837801622069, 481013.0, 489969.25230607844, 492330.0, 492800.0, 493706.0, 57705.13442298285, 58922.70538647279, 59027.40972590305, 59576.404235957954, 59713.701431493435, 1358.6807100331414, 1383.8339399272152, 1388.8333399992066, 1392.6657602175405, 1407.1908652524635, 29350.266243344086, 29493.26266843346, 30417.695823042373, 30683.693163068085, 31044.701904429872, 49813.501864980884, 50669.49330506648, 50947.0, 50972.74513627408, 51029.234561480866, 438363.0, 445245.0, 446368.0, 446961.0, 448509.0, 27705.861470693322, 27802.0, 27875.860620696767, 28079.57880631833, 28103.578446322914, 47590.048199036515, 48075.5192448071, 48536.51463485461, 49450.75274623748, 49548.0, 40213.39679904862, 41355.379669304384, 41887.162256755306, 41983.0, 42033.57966420297, 40356.3946540808, 40437.79781101194, 41140.794296028325, 41510.79244603758, 41852.37221441742, 41158.0, 41246.38130427986, 42293.0, 42370.78814605907, 42928.57071429246, 29953.55069673913, 32481.187970300933, 35398.823005884806, 35539.644603553636, 36263.81868090643, 27480.312992175357, 27538.724612754417, 27841.16476505708, 27921.860390697915, 27941.44117117687], "value": 18494.04681000868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.62417206965114, 382.7760759955426, 384.3293452924646, 391.65534329789796, 393.5316972802363, 17047.846148462526, 18482.537936551595, 18883.433496995094, 19092.904535477337, 19119.330823421184, 17169.74245386317, 17311.567210819736, 17343.0, 17581.0, 19356.12897419616, 5551.084071128259, 5676.664374071751, 5704.971475142618, 5724.0, 5729.971350143243, 5458.181272809071, 5665.943340566603, 5678.0, 5689.4879460848515, 5698.629589076718, 5555.611107222476, 5615.943840561583, 5630.436956304377, 5635.549156067505, 5644.181593668915, 5477.096279113942, 5613.635113717595, 5613.691246981414, 5621.915671264935, 5644.7459864306, 4856.684315519516, 4942.802287908479, 4963.205887058053, 4990.25146228065, 4998.975005124988, 22380.88809555958, 22592.322230333117, 22991.310260692208, 23116.884415577813, 23182.42043948915, 2396.101461951768, 2403.6034054381007, 2445.7921076708503, 2465.4822487277515, 2528.5575024370846, 14046.087004344685, 14319.928400358036, 14559.854401455956, 14616.342264598012, 14701.0, 1882.717592361143, 1927.5277556998626, 1946.6593346164364, 1970.802919708032, 1998.0708970328867, 81097.0, 82697.75953360765, 83110.58444707737, 83143.58428207881, 84454.15545844524, 10120.139788118093, 10169.9491502542, 10422.791544169075, 10555.577776888838, 10765.461726913623, 14669.77995330071, 14761.409543618134, 14923.70152596942, 14959.850401496064, 14980.0, 8192.262696357357, 8291.129431409736, 8393.580320984049, 8511.74464766058, 8574.185452382013, 8272.17278272168, 8294.419390642717, 8297.336213102937, 8304.750857474286, 8330.583470826434, 6363.649999249993, 6413.807585772434, 6511.218653761579, 6664.900026499607, 6704.195496540447, 1855.1373611270824, 1876.3151449720754, 1926.3450426854936, 1936.7966363531907, 1937.1282922684848, 929.3540989012571, 963.6145541783343, 1089.54239219528, 1109.9611513596985, 1151.441550847838, 17118.828811711723, 18704.81295187058, 18810.341638042602, 18892.433227003214, 19260.0, 1046.2571574182416, 1055.904968552834, 1064.8988346107108, 1093.9945300273607, 1097.204526718123, 10846.511906964206, 11042.227044106836, 11070.39112848801, 11183.44082795857, 11220.382878941573, 348979.0, 355254.0, 361727.0, 473715.0, 483406.0, 88841.55579222192, 94428.52785736027, 95907.0, 96962.51518742362, 100785.0, 1094.0919037199105, 1118.1948996722347, 1131.8754936956927, 1133.8922802333766, 1157.6179860645966, 16958.74561881548, 17230.827691722923, 17242.913785430992, 17276.0, 17360.0, 37578.62421375751, 38101.0, 38147.809260953516, 38398.80800595979, 40289.0, 54673.45326546684, 55516.0, 56344.436555635555, 56476.0, 56544.71727641336, 8165.142660020612, 8198.959005204935, 8270.297024752963, 8378.078411374865, 8524.74425767228, 12111.0, 13108.934455328044, 13318.267495287851, 13520.0, 13520.0, 11339.752627210835, 11480.311181329147, 11779.0, 11944.820827687417, 12073.551173858841, 11506.94246528762, 11629.360385178896, 11676.182667213554, 11810.645680629252, 11878.109141814572, 5860.501857342174, 6744.763933262312, 11940.641780746591, 11995.40022998829, 12112.0, 7976.16250293734, 8272.33821294307, 8383.748487545383, 8438.240558349778, 8511.95744021276, 11297.830532541853, 12172.452239649414, 12202.572909948109, 12211.0, 12334.444949977093], "value": 9249.542849070123, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.249399077503, 1444.3861358922456, 1574.79527661404, 1784.6966015777316, 1827.4334956163582, 7495.0, 7933.722319718811, 8025.117237103921, 8297.66809327627, 8309.210624990632, 7944.523328600288, 8053.0, 8065.072516660586, 8070.71752488663, 8078.030636323635, 2264.5810525052875, 2298.184144628658, 2301.5742087713743, 2305.804006659436, 2310.1106074161403, 2275.1240772302617, 2288.5079707862806, 2299.022915261016, 2311.8266130040315, 2317.1774020222747, 2286.4741109544816, 2288.382136823057, 2292.3925159832656, 2308.595995700754, 2308.6306191009444, 2248.415411992877, 2272.522770218256, 2273.340731187962, 2278.8746618936034, 2280.680704701343, 2202.658587918875, 2214.202886960693, 2216.2021672198, 2218.9223377181884, 2219.5449932763745, 8112.837743245161, 8130.3495720342235, 8168.509889406595, 8213.137620549816, 8215.548144852031, 4920.089783390048, 5022.447530771612, 5052.191649336089, 5052.444231134572, 5052.570531504826, 3078.1381213260324, 3083.691630836921, 3185.3310804731036, 3196.5205219217305, 3213.2770126721534, 1001.4727540500794, 1008.7780688248573, 1009.2834087797672, 1023.7133602591286, 1066.0458889294039, 11209.103271738242, 11221.0, 11238.66284011481, 11254.54981800727, 11259.605913792979, 4267.850625228164, 4453.421055262795, 4571.0, 4579.95420045802, 4598.931016034695, 9546.331756776959, 9563.904360956301, 9569.61721531131, 9640.276979226586, 9650.324477286522, 5126.794928202905, 5153.304303918923, 5164.870878227999, 5181.5077567631015, 5184.066867963803, 4178.12259425518, 4600.631949444103, 4736.905261894743, 4769.356136921471, 4788.760561971957, 3839.462475253467, 3845.750026248285, 3911.9608803912165, 3917.490726205632, 3976.6818654508147, 1240.8510978682436, 1241.6461308527028, 1251.7496500699897, 1255.5291765587904, 1273.2296960339047, 932.2588542108904, 938.9061093890693, 954.3653470442217, 968.4141094637783, 976.7167521418709, 5568.916466253009, 5665.745041473061, 5722.942770572325, 5876.559258055663, 5940.138679891392, 454.2096751652102, 457.13145024453803, 465.5786513205554, 468.2811883758483, 475.81681052794926, 3985.0, 4016.457778199964, 4018.216447792711, 4044.1102957349335, 4056.776877271778, 9092.181703646704, 9599.136077753035, 9750.0, 9791.951040244754, 9843.064908833649, 9445.858312125323, 9565.521723913776, 9661.613535458644, 9678.27412944032, 9740.123388895034, 7406.851862962711, 7440.441966852508, 7486.326230639269, 7551.848963020819, 7598.20218877021, 5395.70323632204, 5417.945820541901, 5534.05920993424, 5556.555475561864, 5598.972005139948, 8653.567321633767, 8873.91126088731, 8920.73237802867, 8926.910730892867, 8990.056044115274, 9009.459432434074, 9076.09239076087, 9081.818363632558, 9297.581608827484, 9316.813663726824, 6465.0948867157695, 6877.0, 6945.652717364213, 7019.333163349474, 7095.82260443493, 7847.882281765664, 7909.05091389037, 7925.722599708983, 7932.682692692228, 7933.484323518953, 6953.443724502129, 7135.678894449658, 7185.137783466018, 7217.314355136254, 7318.707251709872, 7153.35619794221, 7210.315020073084, 7269.527480713737, 7277.199508054216, 7399.0, 6503.252126005381, 6631.867362652623, 6633.568818026813, 6639.402453779183, 6643.534952553275, 4882.7558622068045, 4906.141425250496, 4970.204767237225, 5026.421961474477, 5086.923696144635, 5107.719075450737, 5152.156972445889, 5190.455002224863, 5200.739963001759, 5247.317848679647], "value": 4314.427813233302, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.9806811591304, 327.97704160708753, 331.3670888602769, 343.81777657841354, 344.8689497990763, 6082.0, 6128.632282063079, 6136.693165341731, 6169.845753856156, 6174.104754810561, 6008.819735407945, 6041.275046994356, 6042.093685947099, 6048.939510604892, 6068.726907289171, 4196.580341965794, 4426.269665505196, 4549.476810166823, 4566.97716511417, 4570.565796249368, 4565.589096981271, 4588.839390621329, 4595.540445955411, 4595.632349412056, 4605.746683932382, 4539.568740969619, 4546.909061818778, 4549.385832912548, 4550.931736023962, 4572.885677858047, 4254.361845723135, 4491.079328737634, 4500.392447019643, 4556.157110934488, 4569.383133276999, 3861.073342397832, 3861.575226725058, 3868.9940615439905, 3875.2443273561667, 3880.3985382265796, 5812.651240925516, 5815.709214539256, 5820.650760954356, 5838.386969368197, 5846.093855452412, 5026.924596131061, 5200.531952124289, 5255.553277971377, 5292.285541451894, 5296.947030529684, 6251.437370636664, 6267.655278959655, 6290.465310448617, 6325.778597749057, 6326.367363263683, 4324.13517296542, 4355.607995280408, 4359.629431498326, 4359.978200109011, 4363.454568178978, 6403.519736019817, 6410.935890641127, 6585.14393128895, 6621.470282377399, 6710.127683401224, 4442.933355999663, 4452.109578084396, 4461.776911154429, 4463.593967900101, 4481.5070342262325, 4460.598546130864, 4496.865094047183, 4503.88740281489, 4516.706414083074, 4518.615917647036, 5168.483151684802, 5184.403793563713, 5253.527182553512, 5264.499872512105, 5274.841754747364, 6374.9681251594375, 6378.45783108441, 6380.149756570635, 6393.296737358887, 6413.166288382574, 1523.6876440329927, 1529.4111766969797, 1540.8998415102983, 1551.6276093737647, 1553.9456119035779, 964.9131578158, 1003.5734812704571, 1006.6828948881225, 1025.374521541859, 1045.075108528942, 6025.9397406026255, 6034.818955431344, 6046.758129674852, 6046.848828779316, 6049.243844519435, 6484.773032943825, 6531.477481801445, 6533.1180290661105, 6544.821932052277, 6618.437432818265, 5855.59010869235, 5945.881082378328, 5962.002729085718, 5998.7300571473515, 5999.520038396918, 3445.9675485572534, 3472.8610855566, 3492.4935884296633, 3507.859685612547, 3524.7356448266482, 6546.967265163644, 6696.1629796275465, 6708.2956289589865, 6750.898736518956, 6789.879669854418, 6668.733250669919, 6674.83312917181, 6684.364985326386, 6691.1636045494315, 6702.195736511552, 6552.770653027121, 6693.933060669331, 6730.293319201512, 6739.090222819955, 6758.526903116833, 6469.611823290617, 6488.188976377953, 6524.771632993013, 6544.3782840630065, 6550.246721626883, 6298.307186209422, 6389.9552143313695, 6392.712327945346, 6412.743490260524, 6415.775447859302, 6688.899666504909, 6696.732130714911, 6697.564658297195, 6751.45988320943, 6763.797086087426, 4364.912701745884, 4367.519572846921, 4367.563243675734, 4378.824847006084, 4386.298192289219, 6256.968715156395, 6400.807975760735, 6453.612783233021, 6471.676416179078, 6498.155239818793, 6238.500919926304, 6251.843703907439, 6285.968570157303, 6295.811125666238, 6301.8109456716375, 6126.877462450815, 6221.502279817694, 6225.782097626561, 6287.119803227461, 6305.779297724557, 6116.724747386467, 6156.78451254204, 6181.567290289817, 6205.565610407228, 6226.937730622636, 6155.538334624832, 6282.371762823681, 6305.936940630535, 6312.400321969406, 6346.650934198663, 6121.0818377241985, 6199.876002479835, 6206.69659371519, 6218.833381634407, 6247.812565623039], "value": 4736.024448149192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.6198183971576, 769.5267410542516, 790.4545863354289, 822.2024636102982, 824.4805772363412, 13661.726765464697, 13902.374393152308, 13941.790873136912, 13966.790498142524, 13976.0, 14563.0, 14919.92540037301, 14940.178290194035, 14943.850561494382, 15116.697666046672, 693.2339764560139, 814.6863457568849, 836.297510091523, 840.9866111335856, 842.1157784326433, 842.692417267699, 846.1158089796178, 847.4364547575885, 849.4393700157897, 851.2423942691009, 878.6573236437795, 880.7754022724229, 883.8850949376572, 884.3411658314565, 885.4554449013842, 867.6963062927956, 871.7820544863815, 873.9344549158775, 874.1695389380051, 874.396666300254, 633.2179757998867, 636.4590098416355, 636.7389370358177, 639.5619000984341, 641.9454346380563, 15368.769468457982, 15391.692166156727, 15424.922875385664, 15428.845711542854, 15435.536933891888, 13203.603891883258, 13346.86653133466, 13350.465981360734, 13364.532241371506, 13406.411458437306, 10544.261901666887, 10706.892931070668, 10710.625128120557, 10715.732106697395, 10844.67465976022, 3712.7029837612927, 3716.1452865841006, 3775.8867233983024, 3784.394496880486, 3813.046738315421, 63533.682331588505, 63720.362796371905, 64294.357056429304, 64617.67691161514, 64835.99999999953, 10794.24440289188, 10944.17918656104, 10972.396518191417, 11036.889631103746, 11080.667579972614, 17322.82677173212, 17712.557186070193, 17731.113444327733, 17737.0, 20346.694799578017, 8066.596670166468, 8135.3898457615915, 8321.708740194064, 8352.33181345503, 8370.916290837136, 4571.3600095986585, 4599.471060827981, 4654.255319148988, 4711.340412342275, 4735.479097299294, 3187.7449804015623, 3211.7430605551967, 3219.3400352927592, 3232.919177020546, 3249.1877030742316, 600.5555888642373, 604.2567641800522, 611.1993288791697, 616.488314698799, 619.1672200889795, 613.8680183760424, 631.1006815288288, 650.3261514133209, 659.8482349059647, 668.9397954184146, 8702.738917832476, 8835.160659737314, 8919.108089191159, 9010.0, 9053.321000924958, 629.7953165221321, 635.0996423854876, 685.8456847209338, 723.7105157936762, 761.9047619047619, 4152.8131234094535, 4263.33344897825, 4298.591633794785, 4311.461067366579, 4360.258756011485, 62848.68575657184, 63850.36149638537, 64166.0, 64281.0, 65736.34263657397, 56446.0, 57418.425815741306, 57684.42315576791, 58004.0, 58018.709906450196, 48339.75830120827, 48420.27369589459, 48567.0, 51018.23472647913, 52366.738166308925, 8222.62998165072, 8305.0, 8317.376196785144, 8417.663293468193, 8548.829023419621, 33647.663523364456, 34310.48534271938, 34417.655823442445, 34530.0, 35383.0, 45557.77221114006, 46721.29918051164, 47814.28278575755, 47987.520124798306, 48501.27248091352, 10110.545025473724, 10429.687109386427, 11618.128640351839, 11650.475728592064, 11735.41322933867, 13020.218786872818, 13257.602271931857, 13528.79706804379, 13729.382177802223, 13926.094803837717, 12997.0, 13217.669558261121, 13345.933270333975, 13465.259410732504, 13569.1858488491, 12737.29944853042, 13125.671858203239, 13224.801627975781, 13415.48997844892, 13665.385057672229, 11153.0, 12815.487380504675, 13089.149205301625, 13174.077814553273, 13176.275304858325, 10130.949345253226, 10324.22568307365, 10905.945470272914, 11237.269577477438, 11357.261777984735, 10386.948065259625, 10560.84158737635, 10619.522121504397, 10781.568737250736, 10786.244962852525], "value": 6748.595602455192, "unit": "score"}], "name": "dromaeo_css", "value": 7061.834037358153, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 113.0, 111.0, 125.0, 114.0, 113.0, 112.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 128.0, 157.0, 178.0, 130.0, 157.0, 150.0, 130.0, 179.0, 127.0], "value": 148.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 164.0, 164.0, 173.0, 166.0, 164.0, 162.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 159.0, 77.0, 72.0, 76.0, 77.0, 165.0, 77.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 153.0, 95.0, 92.0, 93.0, 147.0, 93.0, 92.0, 145.0], "value": 109.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 119.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 148.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 57.0, 58.0, 58.0, 57.0, 60.0, 62.0, 78.0, 59.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 67.0, 63.0, 62.0, 62.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 77.0, 78.0, 78.0, 77.0, 79.0, 79.0, 79.0, 79.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 128.0, 136.0, 126.0, 132.0, 129.0, 126.0, 159.0, 134.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 168.0, 175.0, 175.0, 175.0, 164.0, 173.0, 164.0, 171.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 67.0, 69.0, 66.0, 70.0, 67.0, 69.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1562.0, "alertThreshold": 2.0}]} 22:34:08 INFO - Return code: 0 22:34:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:34:08 ERROR - # TBPL SUCCESS # 22:34:08 INFO - Running post-action listener: _resource_record_post_action 22:34:08 INFO - Running post-run listener: _resource_record_post_run 22:34:09 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 442368; Write bytes: 288178176; Read time: 84; Write time: 177364 22:34:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1253376; Read time: 0; Write time: 48 22:34:09 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 442368; Write bytes: 286924800; Read time: 84; Write time: 177316 22:34:09 INFO - Running post-run listener: _upload_blobber_files 22:34:09 INFO - Blob upload gear active. 22:34:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:34:09 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 22:34:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:34:09 INFO - (blobuploader) - INFO - Open directory for files ... 22:34:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:34:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:10 INFO - (blobuploader) - INFO - Done attempting. 22:34:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:34:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:34:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:11 INFO - (blobuploader) - INFO - Done attempting. 22:34:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:34:11 INFO - Return code: 0 22:34:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:34:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:34:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/080fd3ec6807466a91304fa95d0671ed4c13d12653742fb9b5bf2ddfa093098f4d7f2bec83a6b5dcd37c02fbbf6488baa26dffd9a88e7e527db4108e12fbfd39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61eee4e6b9ee7f52c47aa7c0515bc279a78d09854ed199c6f0f32d9ae305d4376f91acfd7e9254ba1eab7e9c64973b571c1f4d939ff81ed809f47358b25a0b04"} 22:34:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:34:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:34:11 INFO - Contents: 22:34:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/080fd3ec6807466a91304fa95d0671ed4c13d12653742fb9b5bf2ddfa093098f4d7f2bec83a6b5dcd37c02fbbf6488baa26dffd9a88e7e527db4108e12fbfd39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61eee4e6b9ee7f52c47aa7c0515bc279a78d09854ed199c6f0f32d9ae305d4376f91acfd7e9254ba1eab7e9c64973b571c1f4d939ff81ed809f47358b25a0b04"} 22:34:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:34:11 INFO - Copying logs to upload dir... 22:34:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:34:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.432632 ========= master_lag: 3.81 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-04-23 22:34:15.293615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 22:34:15.297306) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 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/Firefox/sha512/080fd3ec6807466a91304fa95d0671ed4c13d12653742fb9b5bf2ddfa093098f4d7f2bec83a6b5dcd37c02fbbf6488baa26dffd9a88e7e527db4108e12fbfd39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61eee4e6b9ee7f52c47aa7c0515bc279a78d09854ed199c6f0f32d9ae305d4376f91acfd7e9254ba1eab7e9c64973b571c1f4d939ff81ed809f47358b25a0b04"} build_url:https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/KugwRD9JR6O0aAz7ydqYNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/080fd3ec6807466a91304fa95d0671ed4c13d12653742fb9b5bf2ddfa093098f4d7f2bec83a6b5dcd37c02fbbf6488baa26dffd9a88e7e527db4108e12fbfd39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61eee4e6b9ee7f52c47aa7c0515bc279a78d09854ed199c6f0f32d9ae305d4376f91acfd7e9254ba1eab7e9c64973b571c1f4d939ff81ed809f47358b25a0b04"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 22:34:15.324991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:34:15.325270) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461474770.683373-1080382845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 22:34:15.374530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 22:34:15.374889) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 22:34:15.375181) ========= ========= Total master_lag: 3.93 =========