builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-027 starttime: 1462977041.44 results: success (0) buildid: 20160511055410 builduid: 23130035363849e1b7a059821a626cb0 revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.437853) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.438795) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.439120) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.484966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.485594) ========= 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=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 07:30:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2016-05-11 07:30:41 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081129 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.583968) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.584574) ========= 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=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019389 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.635512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:41.635874) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --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 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --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=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 07:30:41,825 truncating revision to first 12 chars 2016-05-11 07:30:41,825 Setting DEBUG logging. 2016-05-11 07:30:41,825 attempt 1/10 2016-05-11 07:30:41,825 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3461f3cae784?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 07:30:42,090 unpacking tar archive at: mozilla-central-3461f3cae784/testing/mozharness/ program finished with exit code 0 elapsedTime=0.546555 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:42.195541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:42.195886) ========= script_repo_revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:42.196287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:42.196577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 07:30:42.204251) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-11 07:30:42.204622) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:30:42 INFO - MultiFileLogger online at 20160511 07:30:42 in /builds/slave/test 07:30:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:30:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:42 INFO - {'append_to_log': False, 07:30:42 INFO - 'base_work_dir': '/builds/slave/test', 07:30:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:30:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:42 INFO - 'branch': 'Firefox-Non-PGO', 07:30:42 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:42 INFO - 'config_files': ('talos/linux_config.py',), 07:30:42 INFO - 'default_actions': ('clobber', 07:30:42 INFO - 'read-buildbot-config', 07:30:42 INFO - 'download-and-extract', 07:30:42 INFO - 'populate-webroot', 07:30:42 INFO - 'create-virtualenv', 07:30:42 INFO - 'install', 07:30:42 INFO - 'run-tests'), 07:30:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:42 INFO - 'download_minidump_stackwalk': True, 07:30:42 INFO - 'download_symbols': 'ondemand', 07:30:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:42 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:42 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:42 INFO - 'installer_path': 'installer.exe', 07:30:42 INFO - 'log_level': 'info', 07:30:42 INFO - 'log_name': 'talos', 07:30:42 INFO - 'log_to_console': True, 07:30:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:30:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:30:42 INFO - 'opt_config_files': (), 07:30:42 INFO - 'pip_index': False, 07:30:42 INFO - 'sps_profile': False, 07:30:42 INFO - 'sps_profile_interval': 0, 07:30:42 INFO - 'suite': 'dromaeojs', 07:30:42 INFO - 'system_bits': '32', 07:30:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:42 INFO - 'title': 'talos-linux64-ix-027', 07:30:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:42 INFO - 'use_talos_json': True, 07:30:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:42 INFO - 'work_dir': 'build'} 07:30:42 INFO - ##### 07:30:42 INFO - ##### Running clobber step. 07:30:42 INFO - ##### 07:30:42 INFO - Running pre-action listener: _resource_record_pre_action 07:30:42 INFO - Running main action method: clobber 07:30:42 INFO - rmtree: /builds/slave/test/build 07:30:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:42 INFO - Running post-action listener: _resource_record_post_action 07:30:42 INFO - ##### 07:30:42 INFO - ##### Finished clobber step (success) 07:30:42 INFO - ##### 07:30:42 INFO - ##### 07:30:42 INFO - ##### Running read-buildbot-config step. 07:30:42 INFO - ##### 07:30:42 INFO - Running pre-action listener: _resource_record_pre_action 07:30:42 INFO - Running main action method: read_buildbot_config 07:30:42 INFO - Using buildbot properties: 07:30:42 INFO - { 07:30:42 INFO - "project": "", 07:30:42 INFO - "product": "firefox", 07:30:42 INFO - "script_repo_revision": "production", 07:30:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:30:42 INFO - "repository": "", 07:30:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:30:42 INFO - "buildid": "20160511055410", 07:30:42 INFO - "pgo_build": "False", 07:30:42 INFO - "basedir": "/builds/slave/test", 07:30:42 INFO - "buildnumber": 572, 07:30:42 INFO - "slavename": "talos-linux64-ix-027", 07:30:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:30:42 INFO - "platform": "ubuntu64_hw", 07:30:42 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:30:42 INFO - "branch": "mozilla-central", 07:30:42 INFO - "repo_path": "mozilla-central", 07:30:42 INFO - "revision": "3461f3cae78495f100a0f7d3d2e0b89292d3ec02", 07:30:42 INFO - "stage_platform": "linux64", 07:30:42 INFO - "builduid": "23130035363849e1b7a059821a626cb0", 07:30:42 INFO - "slavebuilddir": "test" 07:30:42 INFO - } 07:30:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:30:42 INFO - Running post-action listener: _resource_record_post_action 07:30:42 INFO - ##### 07:30:42 INFO - ##### Finished read-buildbot-config step (success) 07:30:42 INFO - ##### 07:30:42 INFO - ##### 07:30:42 INFO - ##### Running download-and-extract step. 07:30:42 INFO - ##### 07:30:42 INFO - Running pre-action listener: _resource_record_pre_action 07:30:42 INFO - Running main action method: download_and_extract 07:30:42 INFO - mkdir: /builds/slave/test/build/tests 07:30: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')]} 07:30:42 INFO - https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:30:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:30:45 INFO - Downloaded 1361 bytes. 07:30:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:30:45 INFO - Using the following test package requirements: 07:30:45 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:30:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:30:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:30:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'jsshell-linux-x86_64.zip'], 07:30:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:30:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:30:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:30:45 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:30:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:30:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:30:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:30:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:45 INFO - https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:30:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:30:46 INFO - Downloaded 22328784 bytes. 07:30:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:30:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:30:47 INFO - Return code: 0 07:30:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:47 INFO - https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:30:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:30:50 INFO - Downloaded 22328784 bytes. 07:30:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:30:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:30:51 INFO - Return code: 0 07:30: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')]} 07:30:51 INFO - https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:30:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:30:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:30:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:30:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:30:52 INFO - Downloaded 10840152 bytes. 07:30:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:30:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:30:52 INFO - Return code: 0 07:30:52 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')]} 07:30:52 INFO - https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:30:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:30:54 INFO - Downloaded 54681998 bytes. 07:30:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:54 INFO - mkdir: /builds/slave/test/properties 07:30:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:54 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:54 INFO - Contents: 07:30:54 INFO - build_url:https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:30:54 INFO - Running post-action listener: _resource_record_post_action 07:30:54 INFO - Running post-action listener: set_extra_try_arguments 07:30:54 INFO - ##### 07:30:54 INFO - ##### Finished download-and-extract step (success) 07:30:54 INFO - ##### 07:30:54 INFO - ##### 07:30:54 INFO - ##### Running populate-webroot step. 07:30:54 INFO - ##### 07:30:54 INFO - Running pre-action listener: _resource_record_pre_action 07:30:54 INFO - Running main action method: populate_webroot 07:30:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:54 INFO - '--tppagecycles', 07:30:54 INFO - '7'], 07:30:54 INFO - 'tests': ['tsvgm']}}, 07:30:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['damp', 'tps']}, 07:30:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'tests': ['damp', 'tps']}, 07:30:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['dromaeo_dom']}, 07:30:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['a11yr', 07:30:54 INFO - 'ts_paint', 07:30:54 INFO - 'tpaint', 07:30:54 INFO - 'sessionrestore', 07:30:54 INFO - 'sessionrestore_no_auto_restore', 07:30:54 INFO - 'tabpaint']}, 07:30:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['tsvgx', 07:30:54 INFO - 'tsvgr_opacity', 07:30:54 INFO - 'tart', 07:30:54 INFO - 'tscrollx', 07:30:54 INFO - 'cart']}, 07:30:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:54 INFO - 'tsvgr_opacity', 07:30:54 INFO - 'tart', 07:30:54 INFO - 'tscrollx', 07:30:54 INFO - 'cart']}, 07:30:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'talos_options': ['--disable-e10s'], 07:30:54 INFO - 'tests': ['tp5o']}, 07:30:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'tests': ['tp5o']}, 07:30:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'talos_options': ['--disable-e10s', 07:30:54 INFO - '--xperf_path', 07:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:54 INFO - 'tests': ['tp5n']}, 07:30:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:54 INFO - 'talos_options': ['--xperf_path', 07:30:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:54 INFO - 'tests': ['tp5n']}}, 07:30:54 INFO - 'talos.zip': {'path': '', 07:30:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:54 INFO - Running post-action listener: _resource_record_post_action 07:30:54 INFO - ##### 07:30:54 INFO - ##### Finished populate-webroot step (success) 07:30:54 INFO - ##### 07:30:54 INFO - ##### 07:30:54 INFO - ##### Running create-virtualenv step. 07:30:54 INFO - ##### 07:30:54 INFO - Running pre-action listener: _resource_record_pre_action 07:30:54 INFO - Running main action method: create_virtualenv 07:30:54 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30:54 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 07:30:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:54 INFO - Using real prefix '/usr' 07:30:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:55 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:57 INFO - Installing pip.................done. 07:30:57 INFO - Return code: 0 07:30:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:57 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:30:57 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 07:30:57 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 07:30:57 INFO - Using env: {'DISPLAY': ':0', 07:30:57 INFO - 'HOME': '/home/cltbld', 07:30:57 INFO - 'LANG': 'en_US.UTF-8', 07:30:57 INFO - 'LANGUAGE': 'en_US:en', 07:30:57 INFO - 'LOGNAME': 'cltbld', 07:30:57 INFO - 'MAIL': '/var/mail/cltbld', 07:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:57 INFO - 'MOZ_NO_REMOTE': '1', 07:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:57 INFO - 'NO_EM_RESTART': '1', 07:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:57 INFO - 'PWD': '/builds/slave/test', 07:30:57 INFO - 'SHELL': '/bin/bash', 07:30:57 INFO - 'SHLVL': '1', 07:30:57 INFO - 'TERM': 'linux', 07:30:57 INFO - 'TMOUT': '86400', 07:30:57 INFO - 'USER': 'cltbld', 07:30:57 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:57 INFO - '_': '/tools/buildbot/bin/python'} 07:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:57 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 07:30:57 INFO - Cleaning up... 07:30:57 INFO - Return code: 0 07:30:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:57 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:30:57 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 07:30:57 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 07:30:57 INFO - Using env: {'DISPLAY': ':0', 07:30:57 INFO - 'HOME': '/home/cltbld', 07:30:57 INFO - 'LANG': 'en_US.UTF-8', 07:30:57 INFO - 'LANGUAGE': 'en_US:en', 07:30:57 INFO - 'LOGNAME': 'cltbld', 07:30:57 INFO - 'MAIL': '/var/mail/cltbld', 07:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:57 INFO - 'MOZ_NO_REMOTE': '1', 07:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:57 INFO - 'NO_EM_RESTART': '1', 07:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:57 INFO - 'PWD': '/builds/slave/test', 07:30:57 INFO - 'SHELL': '/bin/bash', 07:30:57 INFO - 'SHLVL': '1', 07:30:57 INFO - 'TERM': 'linux', 07:30:57 INFO - 'TMOUT': '86400', 07:30:57 INFO - 'USER': 'cltbld', 07:30:57 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:57 INFO - '_': '/tools/buildbot/bin/python'} 07:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:57 INFO - Downloading/unpacking psutil>=0.7.1 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:30:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:59 INFO - Installing collected packages: psutil 07:30:59 INFO - Running setup.py install for psutil 07:30:59 INFO - building 'psutil._psutil_linux' extension 07:30:59 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 07:31:00 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 07:31:00 INFO - building 'psutil._psutil_posix' extension 07:31:00 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 07:31:00 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 07:31:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:00 INFO - Successfully installed psutil 07:31:00 INFO - Cleaning up... 07:31:00 INFO - Return code: 0 07:31:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 07:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 07:31:00 INFO - Using env: {'DISPLAY': ':0', 07:31:00 INFO - 'HOME': '/home/cltbld', 07:31:00 INFO - 'LANG': 'en_US.UTF-8', 07:31:00 INFO - 'LANGUAGE': 'en_US:en', 07:31:00 INFO - 'LOGNAME': 'cltbld', 07:31:00 INFO - 'MAIL': '/var/mail/cltbld', 07:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:00 INFO - 'MOZ_NO_REMOTE': '1', 07:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:00 INFO - 'NO_EM_RESTART': '1', 07:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:00 INFO - 'PWD': '/builds/slave/test', 07:31:00 INFO - 'SHELL': '/bin/bash', 07:31:00 INFO - 'SHLVL': '1', 07:31:00 INFO - 'TERM': 'linux', 07:31:00 INFO - 'TMOUT': '86400', 07:31:00 INFO - 'USER': 'cltbld', 07:31:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:00 INFO - '_': '/tools/buildbot/bin/python'} 07:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:31:00 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 07:31:00 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 07:31:00 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 07:31:00 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 07:31:00 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 07:31:00 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 07:31:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:31:02 INFO - Installing collected packages: mozsystemmonitor 07:31:02 INFO - Running setup.py install for mozsystemmonitor 07:31:02 INFO - Successfully installed mozsystemmonitor 07:31:02 INFO - Cleaning up... 07:31:02 INFO - Return code: 0 07:31:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:02 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:02 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 07:31:02 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 07:31:02 INFO - Using env: {'DISPLAY': ':0', 07:31:02 INFO - 'HOME': '/home/cltbld', 07:31:02 INFO - 'LANG': 'en_US.UTF-8', 07:31:02 INFO - 'LANGUAGE': 'en_US:en', 07:31:02 INFO - 'LOGNAME': 'cltbld', 07:31:02 INFO - 'MAIL': '/var/mail/cltbld', 07:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:02 INFO - 'MOZ_NO_REMOTE': '1', 07:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:02 INFO - 'NO_EM_RESTART': '1', 07:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:02 INFO - 'PWD': '/builds/slave/test', 07:31:02 INFO - 'SHELL': '/bin/bash', 07:31:02 INFO - 'SHLVL': '1', 07:31:02 INFO - 'TERM': 'linux', 07:31:02 INFO - 'TMOUT': '86400', 07:31:02 INFO - 'USER': 'cltbld', 07:31:02 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:02 INFO - '_': '/tools/buildbot/bin/python'} 07:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:02 INFO - Downloading/unpacking blobuploader==1.2.4 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:04 INFO - Downloading blobuploader-1.2.4.tar.gz 07:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:31:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:31:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:05 INFO - Downloading docopt-0.6.1.tar.gz 07:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:31:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:31:05 INFO - Running setup.py install for blobuploader 07:31:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:31:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:31:05 INFO - Running setup.py install for requests 07:31:05 INFO - Running setup.py install for docopt 07:31:06 INFO - Successfully installed blobuploader requests docopt 07:31:06 INFO - Cleaning up... 07:31:06 INFO - Return code: 0 07:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:06 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:06 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 07:31:06 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 07:31:06 INFO - Using env: {'DISPLAY': ':0', 07:31:06 INFO - 'HOME': '/home/cltbld', 07:31:06 INFO - 'LANG': 'en_US.UTF-8', 07:31:06 INFO - 'LANGUAGE': 'en_US:en', 07:31:06 INFO - 'LOGNAME': 'cltbld', 07:31:06 INFO - 'MAIL': '/var/mail/cltbld', 07:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:06 INFO - 'MOZ_NO_REMOTE': '1', 07:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:06 INFO - 'NO_EM_RESTART': '1', 07:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:06 INFO - 'PWD': '/builds/slave/test', 07:31:06 INFO - 'SHELL': '/bin/bash', 07:31:06 INFO - 'SHLVL': '1', 07:31:06 INFO - 'TERM': 'linux', 07:31:06 INFO - 'TMOUT': '86400', 07:31:06 INFO - 'USER': 'cltbld', 07:31:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:06 INFO - '_': '/tools/buildbot/bin/python'} 07:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:06 INFO - Running setup.py (path:/tmp/pip-BPTnW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:06 INFO - Running setup.py (path:/tmp/pip-3UJNQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:06 INFO - Running setup.py (path:/tmp/pip-dPjorL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:06 INFO - Running setup.py (path:/tmp/pip-fQdqSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:06 INFO - Running setup.py (path:/tmp/pip-_1SO9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:06 INFO - Running setup.py (path:/tmp/pip-i0cQAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:06 INFO - Running setup.py (path:/tmp/pip-9_vR_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:06 INFO - Running setup.py (path:/tmp/pip-D42DS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:06 INFO - Running setup.py (path:/tmp/pip-C4BGcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:06 INFO - Running setup.py (path:/tmp/pip-yRh85i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:07 INFO - Running setup.py (path:/tmp/pip-Hdn5HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:07 INFO - Running setup.py (path:/tmp/pip-sO_jGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:07 INFO - Running setup.py (path:/tmp/pip-uzEghV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:07 INFO - Running setup.py (path:/tmp/pip-iSTx0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:07 INFO - Running setup.py (path:/tmp/pip-MYjWAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:07 INFO - Running setup.py (path:/tmp/pip-FC0g6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:07 INFO - Running setup.py (path:/tmp/pip-3i344w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:07 INFO - Running setup.py install for manifestparser 07:31:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:07 INFO - Running setup.py install for mozcrash 07:31:07 INFO - Running setup.py install for mozdebug 07:31:07 INFO - Running setup.py install for mozdevice 07:31:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozfile 07:31:08 INFO - Running setup.py install for mozhttpd 07:31:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozinfo 07:31:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozInstall 07:31:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozleak 07:31:08 INFO - Running setup.py install for mozlog 07:31:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for moznetwork 07:31:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozprocess 07:31:08 INFO - Running setup.py install for mozprofile 07:31:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:08 INFO - Running setup.py install for mozrunner 07:31:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:09 INFO - Running setup.py install for mozscreenshot 07:31:09 INFO - Running setup.py install for moztest 07:31:09 INFO - Running setup.py install for mozversion 07:31:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:09 INFO - Cleaning up... 07:31:09 INFO - Return code: 0 07:31:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:09 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')]} 07:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 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')]} 07:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:09 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 07:31:09 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 07:31:09 INFO - Using env: {'DISPLAY': ':0', 07:31:09 INFO - 'HOME': '/home/cltbld', 07:31:09 INFO - 'LANG': 'en_US.UTF-8', 07:31:09 INFO - 'LANGUAGE': 'en_US:en', 07:31:09 INFO - 'LOGNAME': 'cltbld', 07:31:09 INFO - 'MAIL': '/var/mail/cltbld', 07:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:09 INFO - 'MOZ_NO_REMOTE': '1', 07:31:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:09 INFO - 'NO_EM_RESTART': '1', 07:31:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:09 INFO - 'PWD': '/builds/slave/test', 07:31:09 INFO - 'SHELL': '/bin/bash', 07:31:09 INFO - 'SHLVL': '1', 07:31:09 INFO - 'TERM': 'linux', 07:31:09 INFO - 'TMOUT': '86400', 07:31:09 INFO - 'USER': 'cltbld', 07:31:09 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:09 INFO - '_': '/tools/buildbot/bin/python'} 07:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:09 INFO - Running setup.py (path:/tmp/pip-MKOMwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:09 INFO - Running setup.py (path:/tmp/pip-8070kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:09 INFO - Running setup.py (path:/tmp/pip-KfPyqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:09 INFO - Running setup.py (path:/tmp/pip-RmGuA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:09 INFO - Running setup.py (path:/tmp/pip-ogzjnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:09 INFO - Running setup.py (path:/tmp/pip-09oi0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31: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)) 07:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:09 INFO - Running setup.py (path:/tmp/pip-oTm3wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:10 INFO - Running setup.py (path:/tmp/pip-Ot5To7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:10 INFO - Running setup.py (path:/tmp/pip-TgwcXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:10 INFO - Running setup.py (path:/tmp/pip-4UXbxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:10 INFO - Running setup.py (path:/tmp/pip-5BJd29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:10 INFO - Running setup.py (path:/tmp/pip-5IsyLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:10 INFO - Running setup.py (path:/tmp/pip-TJg_b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:10 INFO - Running setup.py (path:/tmp/pip-Ps9zan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:10 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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:10 INFO - Running setup.py (path:/tmp/pip-uINRTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31: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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:10 INFO - Running setup.py (path:/tmp/pip-AUHxfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31: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)) 07:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:10 INFO - Running setup.py (path:/tmp/pip-shVjE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31: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)) 07:31: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)) 07:31: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)) 07:31: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)) 07:31: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)) 07:31: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)) 07:31:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:12 INFO - Downloading blessings-1.6.tar.gz 07:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:12 INFO - Installing collected packages: blessings 07:31:12 INFO - Running setup.py install for blessings 07:31:12 INFO - Successfully installed blessings 07:31:12 INFO - Cleaning up... 07:31:12 INFO - Return code: 0 07:31:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:13 INFO - Reading from file tmpfile_stdout 07:31:13 INFO - Current package versions: 07:31:13 INFO - argparse == 1.2.1 07:31:13 INFO - blessings == 1.6 07:31:13 INFO - blobuploader == 1.2.4 07:31:13 INFO - docopt == 0.6.1 07:31:13 INFO - manifestparser == 1.1 07:31:13 INFO - mozInstall == 1.12 07:31:13 INFO - mozcrash == 0.17 07:31:13 INFO - mozdebug == 0.1 07:31:13 INFO - mozdevice == 0.48 07:31:13 INFO - mozfile == 1.2 07:31:13 INFO - mozhttpd == 0.7 07:31:13 INFO - mozinfo == 0.9 07:31:13 INFO - mozleak == 0.1 07:31:13 INFO - mozlog == 3.1 07:31:13 INFO - moznetwork == 0.27 07:31:13 INFO - mozprocess == 0.22 07:31:13 INFO - mozprofile == 0.28 07:31:13 INFO - mozrunner == 6.11 07:31:13 INFO - mozscreenshot == 0.1 07:31:13 INFO - mozsystemmonitor == 0.0 07:31:13 INFO - moztest == 0.7 07:31:13 INFO - mozversion == 1.4 07:31:13 INFO - psutil == 3.1.1 07:31:13 INFO - requests == 1.2.3 07:31:13 INFO - wsgiref == 0.1.2 07:31:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:13 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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:13 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 07:31:13 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 07:31:13 INFO - Using env: {'DISPLAY': ':0', 07:31:13 INFO - 'HOME': '/home/cltbld', 07:31:13 INFO - 'LANG': 'en_US.UTF-8', 07:31:13 INFO - 'LANGUAGE': 'en_US:en', 07:31:13 INFO - 'LOGNAME': 'cltbld', 07:31:13 INFO - 'MAIL': '/var/mail/cltbld', 07:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:13 INFO - 'MOZ_NO_REMOTE': '1', 07:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:13 INFO - 'NO_EM_RESTART': '1', 07:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:13 INFO - 'PWD': '/builds/slave/test', 07:31:13 INFO - 'SHELL': '/bin/bash', 07:31:13 INFO - 'SHLVL': '1', 07:31:13 INFO - 'TERM': 'linux', 07:31:13 INFO - 'TMOUT': '86400', 07:31:13 INFO - 'USER': 'cltbld', 07:31:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:13 INFO - '_': '/tools/buildbot/bin/python'} 07:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 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)) 07:31:13 INFO - Cleaning up... 07:31:13 INFO - Return code: 0 07:31:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:31:13 INFO - Using env: {'DISPLAY': ':0', 07:31:13 INFO - 'HOME': '/home/cltbld', 07:31:13 INFO - 'LANG': 'en_US.UTF-8', 07:31:13 INFO - 'LANGUAGE': 'en_US:en', 07:31:13 INFO - 'LOGNAME': 'cltbld', 07:31:13 INFO - 'MAIL': '/var/mail/cltbld', 07:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:13 INFO - 'MOZ_NO_REMOTE': '1', 07:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:13 INFO - 'NO_EM_RESTART': '1', 07:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:13 INFO - 'PWD': '/builds/slave/test', 07:31:13 INFO - 'SHELL': '/bin/bash', 07:31:13 INFO - 'SHLVL': '1', 07:31:13 INFO - 'TERM': 'linux', 07:31:13 INFO - 'TMOUT': '86400', 07:31:13 INFO - 'USER': 'cltbld', 07:31:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:13 INFO - '_': '/tools/buildbot/bin/python'} 07:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:13 INFO - Downloading/unpacking jsonschema 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:15 INFO - Downloading/unpacking functools32 (from jsonschema) 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:15 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:15 INFO - warning: no files found matching '*.txt' 07:31:15 INFO - no previously-included directories found matching 'build' 07:31:15 INFO - no previously-included directories found matching 'dist' 07:31:15 INFO - no previously-included directories found matching '.git*' 07:31:15 INFO - Installing collected packages: jsonschema, functools32 07:31:15 INFO - Running setup.py install for functools32 07:31:15 INFO - warning: no files found matching '*.txt' 07:31:15 INFO - no previously-included directories found matching 'build' 07:31:15 INFO - no previously-included directories found matching 'dist' 07:31:15 INFO - no previously-included directories found matching '.git*' 07:31:15 INFO - Successfully installed jsonschema functools32 07:31:15 INFO - Cleaning up... 07:31:15 INFO - Return code: 0 07:31:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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 0x7fbd60891f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fc3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, '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': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', '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 07:31: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 07:31: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 07:31:15 INFO - Using env: {'DISPLAY': ':0', 07:31:15 INFO - 'HOME': '/home/cltbld', 07:31:15 INFO - 'LANG': 'en_US.UTF-8', 07:31:15 INFO - 'LANGUAGE': 'en_US:en', 07:31:15 INFO - 'LOGNAME': 'cltbld', 07:31:15 INFO - 'MAIL': '/var/mail/cltbld', 07:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:15 INFO - 'MOZ_NO_REMOTE': '1', 07:31:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:15 INFO - 'NO_EM_RESTART': '1', 07:31:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:15 INFO - 'PWD': '/builds/slave/test', 07:31:15 INFO - 'SHELL': '/bin/bash', 07:31:15 INFO - 'SHLVL': '1', 07:31:15 INFO - 'TERM': 'linux', 07:31:15 INFO - 'TMOUT': '86400', 07:31:15 INFO - 'USER': 'cltbld', 07:31:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:15 INFO - '_': '/tools/buildbot/bin/python'} 07:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:16 INFO - Downloading/unpacking flake8 07:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:31:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:31:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:31:18 INFO - no previously-included directories found matching 'docs/_build' 07:31:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:31:18 INFO - Running setup.py install for pep8 07:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:31:18 INFO - no previously-included directories found matching 'docs/_build' 07:31:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:31:18 INFO - Cleaning up... 07:31:18 INFO - Return code: 0 07:31:18 INFO - Running post-action listener: _resource_record_post_action 07:31:18 INFO - Running post-action listener: _start_resource_monitoring 07:31:18 INFO - Starting resource monitoring. 07:31:18 INFO - ##### 07:31:18 INFO - ##### Finished create-virtualenv step (success) 07:31:18 INFO - ##### 07:31:18 INFO - ##### 07:31:18 INFO - ##### Running install step. 07:31:18 INFO - ##### 07:31:18 INFO - Running pre-action listener: _resource_record_pre_action 07:31:18 INFO - Running main action method: install 07:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:19 INFO - Reading from file tmpfile_stdout 07:31:19 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:19 INFO - Reading from file tmpfile_stdout 07:31:19 INFO - Output received: 07:31:19 INFO - Usage: mozinstall [options] installer 07:31:19 INFO - Options: 07:31:19 INFO - -h, --help show this help message and exit 07:31:19 INFO - -d DEST, --destination=DEST 07:31:19 INFO - Directory to install application into. [default: 07:31:19 INFO - "/builds/slave/test"] 07:31:19 INFO - --app=APP Application being installed. [default: firefox] 07:31:19 INFO - mkdir: /builds/slave/test/build/application 07:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:31:35 INFO - Reading from file tmpfile_stdout 07:31:35 INFO - Output received: 07:31:35 INFO - /builds/slave/test/build/application/firefox/firefox 07:31:35 INFO - Running post-action listener: _resource_record_post_action 07:31:35 INFO - ##### 07:31:35 INFO - ##### Finished install step (success) 07:31:35 INFO - ##### 07:31:35 INFO - ##### 07:31:35 INFO - ##### Running run-tests step. 07:31:35 INFO - ##### 07:31:35 INFO - Running pre-action listener: _resource_record_pre_action 07:31:35 INFO - Running main action method: run_tests 07:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:35 INFO - Python 2.7.3 07:31:35 INFO - Return code: 0 07:31:35 INFO - grabbing minidump binary from tooltool 07:31: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')]} 07:31: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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20529c0>, '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 0x20538c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2053d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31: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 07:31: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 07:31:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:31:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:35 INFO - Return code: 0 07:31:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:31:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:31:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:31:36 INFO - Return code: 0 07:31:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:31:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:31:36 INFO - Using env: {'DISPLAY': ':0', 07:31:36 INFO - 'HOME': '/home/cltbld', 07:31:36 INFO - 'LANG': 'en_US.UTF-8', 07:31:36 INFO - 'LANGUAGE': 'en_US:en', 07:31:36 INFO - 'LOGNAME': 'cltbld', 07:31:36 INFO - 'MAIL': '/var/mail/cltbld', 07:31:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:36 INFO - 'MOZ_NO_REMOTE': '1', 07:31:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:36 INFO - 'NO_EM_RESTART': '1', 07:31:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:36 INFO - 'PWD': '/builds/slave/test', 07:31:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:36 INFO - 'SHELL': '/bin/bash', 07:31:36 INFO - 'SHLVL': '1', 07:31:36 INFO - 'TERM': 'linux', 07:31:36 INFO - 'TMOUT': '86400', 07:31:36 INFO - 'USER': 'cltbld', 07:31:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644', 07:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:36 INFO - '_': '/tools/buildbot/bin/python'} 07:31:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:31:36 INFO - mozversion application_buildid: 20160511055410 07:31:36 INFO - mozversion application_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 07:31:36 INFO - mozversion application_display_name: Nightly 07:31:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:36 INFO - mozversion application_name: Firefox 07:31:36 INFO - mozversion application_remotingname: firefox 07:31:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:31:36 INFO - mozversion application_vendor: Mozilla 07:31:36 INFO - mozversion application_version: 49.0a1 07:31:36 INFO - mozversion platform_buildid: 20160511055410 07:31:36 INFO - mozversion platform_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 07:31:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:31:36 INFO - mozversion platform_version: 49.0a1 07:31:36 INFO - using testdate: 1462977096 07:31:36 INFO - actual date: 1462977096 07:31:36 INFO - starting webserver on 'localhost:42558' 07:31:36 INFO - SUITE-START | Running 2 tests 07:31:36 INFO - TEST-START | dromaeo_css 07:31:36 INFO - operating with platform_type : linux_ 07:31:36 INFO - Initialising browser for dromaeo_css test... 07:31:36 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWA_gY/profile http://localhost:42558/getInfo.html) 07:31:40 INFO - PROCESS | 2304 | __metrics Screen width/height:1600/1200 07:31:40 INFO - PROCESS | 2304 | colorDepth:24 07:31:40 INFO - PROCESS | 2304 | Browser inner width/height: 1024/697 07:31:40 INFO - PROCESS | 2304 | __metrics 07:31:41 ERROR - PROCESS | 2304 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:31:42 INFO - TEST-INFO | 2304: exit 0 07:31:42 INFO - Browser initialized. 07:31:42 INFO - Running cycle 1/1 for dromaeo_css test... 07:31:42 INFO - TEST-INFO | started process 2453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWA_gY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:54 INFO - PROCESS | 2453 | RSS: Main: 154177536 07:31:54 INFO - PROCESS | 2453 | 07:32:45 INFO - PROCESS | 2453 | 1462977165411 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] 07:34:57 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42558/tests/dromaeo/cssquery-ext.html) 07:34:58 INFO - PROCESS | 2453 | RSS: Main: 267718656 07:34:58 INFO - PROCESS | 2453 | 07:38:00 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-ext.html (next: http://localhost:42558/tests/dromaeo/cssquery-jquery.html) 07:38:01 INFO - PROCESS | 2453 | RSS: Main: 242122752 07:38:01 INFO - PROCESS | 2453 | 07:41:03 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42558/tests/dromaeo/cssquery-mootools.html) 07:41:04 INFO - PROCESS | 2453 | RSS: Main: 233766912 07:41:04 INFO - PROCESS | 2453 | 07:44:06 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42558/tests/dromaeo/cssquery-prototype.html) 07:44:06 INFO - PROCESS | 2453 | RSS: Main: 232955904 07:44:06 INFO - PROCESS | 2453 | 07:47:09 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42558/tests/dromaeo/cssquery-yui.html) 07:47:10 INFO - PROCESS | 2453 | RSS: Main: 212979712 07:47:10 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | Cycle 1(1): loaded http://localhost:42558/tests/dromaeo/cssquery-yui.html (next: http://localhost:42558/tests/dromaeo/cssquery-dojo.html) 07:50:13 INFO - PROCESS | 2453 | RSS: Main: 270774272 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | __start_tp_report 07:50:13 INFO - PROCESS | 2453 | _x_x_mozilla_page_load 07:50:13 INFO - PROCESS | 2453 | _x_x_mozilla_page_load_details 07:50:13 INFO - PROCESS | 2453 | |i|pagename|runs| 07:50:13 INFO - PROCESS | 2453 | |0;dojo.html;2631.657884475018;2676.4379480309135;2697.312185392725;2711.7152698966606;2711.7423844734753;4681.508441613632;4744.07490539345;4749.76251187441;4820.710757354561;4877.341558889549;4745.952540474603;4746.335513028179;4772.4273087229585;4784.378030855993;4856.198727210006;4391.516933137353;4403.801828917698;4404.97797511012;4455.242608756519;4462.665300102489;4430.158269928719;4510.210713125206;4510.345999830022;4546.113507865967;4549.7042692224895;4441.244988351988;4478.171538265431;4485.887852803673;4494.37078808967;4513.864584062467;4286.249906266391;4293.656507479394;4348.934765978513;4413.095315460323;4435.401220835178;4287.206866729665;4291.463567054118;4291.849785257501;4329.177456283296;4329.523752387235;4650.18621741194;4674.948136669256;4678.929816052762;4683.531646835323;4693.976530117362;4996.100701873661;5045.940352525974;5071.974640126776;5078.923816142724;5106.055379754757;5134.948650513522;5142.280080788692;5147.253648220987;5183.792648294064;5189.403218629868;4790.664653474259;4844.103840789465;4845.53967373099;4846.733429661403;4848.806047758121;5116.667416617908;5127.179651255781;5177.378714554277;5185.559227465707;5271.6309858310315;4986.90026199474;5015.14742493777;5019.046381187636;5065.898682026339;5066.03745288394;4971.751412429437;5084.084864724311;5097.388313402341;5123.3339665843205;5161.4322424533275;5033.395992480926;5034.370703662043;5079.974600126976;5098.4646612105935;5109.9744501278;5079.288899554065;5097.490250974947;5105.183170692746;5121.641485096082;5148.665336753174;4287.614114729689;4310.676699247569;4383.583559561836;4417.536158703354;4456.977715111469;3770.1328694399895;3790.5451345838674;3805.1438426353848;3829.463875057494;3834.750741201813;4374.103308821684;4393.362962370502;4397.1865204937185;4400.427944367212;4445.155420470101;5061.44324124346;5105.744712764421;5133.383993920754;5149.897002059938;5186.325777648957;4377.474703035656;4398.098389830077;4403.4495688039;4449.399331090326;4451.666125040635;4554.567316104964;4570.223062079454;4582.931256031162;4583.449986001701;4633.698809577367;4740.525947405232;4741.596964258077;4781.067691800065;4821.831235906727;4852.320675105418;5040.924386134284;5052.040112378636;5057.848264552069;5122.231665250242;5158.0199762045095;4813.205821039489;4826.541478559531;4827.348307978378;4838.153323168361;4840.540148685869;4961.5534601886;4985.925211121764;5016.724080175625;5029.974850125726;5079.390473143247;5094.2358646203365;5094.490550944876;5117.565006974449;5149.716765577929;5234;4993.076280887902;4993.825216117419;5037.748112594429;5041.722705251246;5043.5712964398435;4861.4895435978715;4886.609071274361;4891.972685735893;4915.95084049155;4949.777260023379;4855.781489832895;4919.545964824747;4931.457539670705;4966.925496117489;4980.352554167935;4944.085344211188;5028.270900719302;5037.748112594429;5066.771995260148;5086.033653605803;4819.759012049453;4821.252705830602;4845.53967373099;4851;4921.655484116221 07:50:13 INFO - PROCESS | 2453 | |1;ext.html;581.6887964938759;606.9180660610816;608.1091201389963;616.3589866538799;631.6178711879312;28109.578356324644;42378.57621423789;43023.78488107559;43028.354574681325;47427.28859067117;43086.56913430865;43523.12953740923;43565.34651980215;43599;43654.563454365525;7772.300492955647;8023.719169829057;8026.197380261986;8026.277635012848;8031.799205019892;7849.64676589552;7951.9602401988195;7954.642041108135;7956.204379562027;7957.124716281204;7987.08148562914;8035.959820200891;8036.236557527026;8038.477498962578;8039.276465118138;7944.285014348708;7970.202979702041;7988.041435027834;7993.44045916786;7995.840083198304;7899.684012639545;7990.12108668046;7993.560354180518;7994.400419968468;7996.8400631987615;40275.39586906199;40431.393529097084;40594.79702601468;40658.796706016576;40736.38895416571;3350.329934013207;3439.570053743282;3443.483477478373;3563.1092226943265;3719.925601487982;29957.55063674025;30274.697253027407;30499.69500305013;31515.369692606248;31870.521942170886;3507.4037413639744;3516.7362447816517;3539.4159963605975;3566.982165089184;3745.194783121615;245066;246287;246990.47571432035;248454;248504;35834.28331433357;37113.25773484569;56477.43522564804;56916;57025.71487142538;34685.82657086749;34761.478577821355;34777.65222347795;34812.477812832825;34839;14195.290235488184;14338.928305358406;14348.139111653334;14356;14357.712845743026;11721.88278117225;11811.763764724657;11815.822762658396;11826.822597661041;11828.34944078084;8248.051474080557;8319.084900660922;8338.207870252438;8395.790105247417;8402.705905293285;3296.7472360502857;3297.4888892221743;3301.7853839500353;3324.8337583120747;3325.783824051429;955.5843208204453;959.203860795538;966.8646389505475;999.3703966501204;1057.8889216632297;28193.57709634357;28356.149315520568;28433.715662843522;28535.714642853305;28815.56776648352;999.5352161245056;1077.272840832435;1078.8166011778017;1134.597217987619;1189.940502974865;14930.850691492946;15015.249237538079;15291;15297.541073767805;15517.146556939251;306103;308319;311142;312261;314617;33539.832300838825;33575.16062098467;33696.83151584276;33756;33841.32317353639;1186.2976628537904;1213.8482689663792;1226.7791797476361;1285.4729560879994;1294.210531575738;27151.59272610952;29991.250218744706;30269;30724.231894202825;30806.537901931937;43817.561824382625;44354.55645443504;44387.77806111078;44409.11181776411;44619.55380446284;272668;274443;275346;275559;276710;26457.206283811516;26849.328766780985;27887.581686274316;27896.302592435353;29287;43704.34443483287;43982.340264896695;43998.78000610104;45031.324530132726;45128.77435612801;37648.81175594214;38151.236975260894;38561.22877542378;38713;39060.609393905695;37706.8114659425;37739.62260377361;38003.80998094992;38146.237075257784;38189.809050954565;37551.43672844964;38703.80648096742;39154.80422597869;39278;42533.78733106315;31816.363672725955;34199.31601367909;35019.47470787889;35079.298414032084;35113.824430877685;26565.33586660349;26627.33431664224;26703.19890403291;26732.866335668197;27142.592861107492 07:50:13 INFO - PROCESS | 2453 | |2;jquery.html;388.9202713443743;391.05761974301913;391.49497148678205;395.76452011053425;403.298261025815;16245.35018599256;17041.147942602853;17300.567485812862;17545.859519131263;17578.824211757877;16870.493885183434;17153.228104735284;17280.48158555242;17408.912955435237;17555.38556150535;5038.773255203516;5211.258473033435;5223.947760522403;5235.083860324447;5277.525022747952;5031.949680503185;5232.215167724853;5234.502722241381;5237.031149237403;5237.502437268454;5171.844844654647;5184.637075404723;5188.507091826271;5190.7664155112925;5191.714455704935;5012.974935125301;5146.150885103953;5191.247269145991;5221.843344699646;5222.268882356473;4598.103369842882;4607.654425918069;4609.585137337655;4609.654275929319;4612.86161415158;19627.80372196274;19937.800621993883;20043.6993445097;20295.579309448345;20608.793912060988;1966.8918209498472;2012.3333366327731;2164.8701077935216;2169.7070895429074;2593.922182334533;12989.805152922714;12993.545225917047;13078.803817942739;13159.144655597356;13517.797233041512;1707.2488105233658;1729.852962498189;1753.3863147898262;1761.9559511012328;1776.769020027401;67641.32358676399;68381;68558.65720671414;68990.31009689889;70351;9453.763655908657;9574.425534467955;9870.111689947938;9878.308518403786;9909.454979976026;13699.315034248248;13740.10689305192;13786.103903246258;13818.792718109236;13830.861691382957;7099.751508697171;7165.211826699059;7178.021121234734;7221.53060051095;7445;6677.499187560952;6679.298673639295;6728.966355168192;6779.898301525481;7026.789196324118;6192.380761923861;6234.532410069263;6290.119383286344;6330.39861213184;6333.65164915934;1795.9191836367395;1814.219885449243;1828.7896891857606;1831.2674930027829;1847.5842935339442;907.1925985872579;920.0845159066771;938.9624415023459;981.5975450065439;1004.9346792458467;16349.591260218589;16691.49925502237;17084.316627335014;17171.484855454357;17243.341452701334;890.2699786175274;912.466207268742;921.3734660430971;959.5873774277128;1025.779457416659;9764.218862490983;9767.413955162712;9833.557489912972;10014.899851001397;10121.69634910954;411421;419489;426783;426789;441700.7489775312;52447.73776131171;56348.43651563432;63584.36415635784;68741;69555;912.6668765900398;919.8528235482424;942.137943781446;964.4068897628049;971.6404930175739;15291.235438228267;15541.689166216387;15604.765928510855;15901.125438101015;16039.759403609189;25197;26295.342616434744;26836.731632683423;31383;36042.63957360393;50860.456977151734;51931.48068519266;52853.735731321096;52862.20706689326;53468;7354.96322518384;7750.651220694969;7795.961020194863;7819;7945.404094693036;10017.248706346907;11391.373474458984;11549.249298795552;11670.591529296435;11797.646070617895;10365.896341036492;10416.270861039655;10452.529636166542;10464.267501274837;10522.842157367493;5639.7180140993605;8886.377953543448;9605.855912161183;10341.37951722899;10665.786684266426;10689.358638481715;10714.303570267908;10877.075448586642;10977.615783447542;11072.114230861673;7843.764687059397;8017;8042.155573664681;8048.315893148913;8182.181781822008;7947.960260198662;9758.51207439611;10191.94904025475;10384;10499.68500944973 07:50:13 INFO - PROCESS | 2453 | |3;mootools.html;1301.5769874790694;1490.426185918421;1538.1770752647333;1709.8119206887247;1870.4669169286756;6772.255051944288;7553.924460755391;7737.419693522994;7753.806154846132;7971.322437592797;7955.085165206003;8114.513129212251;8119.91880081199;8125.674973001072;8130.3902207334395;2280.999999999996;2308.4228942764307;2323.2101085630884;2328.196772113618;2329.685492458513;2257.3792207143;2274.4200228941595;2322.9883850580723;2325.3140323604493;2328.813694904412;2279.4415368234804;2299.540091981602;2304;2311.144876395736;2314.513952070059;2197.7582465928735;2202.2622421488823;2203.2508946958033;2205.636070048456;2211.203966572025;2102.6740855167473;2107.6100921329603;2109.683547467877;2111.1766411099684;2112.4824418017683;7801.5709135997495;7862.488938218998;7872.212778722082;7875.291223789886;8048;4723.267893476483;4853.733044682541;4858.854234372974;4880.926196236822;4882.658213925027;2941.676415594283;2957.822530648168;2964.7331740143277;2975.940481190364;3012.774041946863;950.2208189284789;951.5908159491416;956.3592393096573;958.228625956105;959.3764053365369;11121.55513779448;11178.944105279503;11227.157963152715;11267.830982535186;11290.153238507062;4173.207090652766;4216.093539888939;4277.2087163874785;4297.67767417445;4371.715838470489;8734;8804.339674524435;8806.691765788166;8815.471071735716;8820.558972051373;4846.248831431134;4871.48849370811;4874.683145595525;4879.487653796371;4880.463149053601;3819.236152769457;4153.252414565407;4155.459790227251;4267.210566045292;4269.1034882674985;3513.0865974846724;3563.1804684922613;3599.6760291573883;3631.418972964366;3669.412893936957;1072.399456304472;1080.5353697909818;1089.8855620159768;1096.4243772019595;1100.801855665988;755.8148253677774;819.4632515702225;932.0399988012358;937.6718148648055;960.8943016268205;5555.2222688823595;5620.690862002547;5625.54995600351;5730.598858079894;5826.883462330729;476.8926991426901;482.11772456404697;483.6952719786512;490.6393800556588;637.8327660381491;3705.1292946157632;3813.3326667833016;3815.7138214634015;3816.694664426839;3829.3872980323013;9540.189083927806;9990.050945160197;10020.749481263027;10140.391576505433;10166.796664066676;9712.417254964725;9930.602775889029;10090.798184036279;10143.543540540693;10166.796664066826;7471.663775130132;7573.091229052549;7576.090869095634;7649.9290099386135;7793.961030194812;5598.804041858515;5634.464725851038;5690.71546422669;5734.283214598176;5788.363280039108;9277.582508787256;9286.30352723535;9369.765755856159;9374.906250937403;9560.521973901137;8985.685501007434;9130.863037054582;9152.4966126861;9275.16523512887;9481.905180948102;7502.137254215617;7691.88462173079;7906.367490600853;7912.564808935564;7919.049714034353;7744.612769361397;7765.301122898966;7827.530348179127;7830.33442157423;7879.645415956181;7247.094113235846;7258.782236532912;7324.890126648211;7436.665350059152;7449.106107266946;6471.579347342407;6968.303169682991;7147.035150254649;7158.821029474305;7281.635918204174;6396.872062558629;6496.058071579689;6498.187726534184;6505.7072431741635;6512.05575191604;4675.789589468414;4804.663673542818;4892.339534162985;4903.387076615423;4977.278294647328;4803.687760295569;4862.562369386772;4955.950440495549;4973.278874563241;4973.676711013773 07:50:13 INFO - PROCESS | 2453 | |4;prototype.html;298.4389348025711;298.9656189538203;303.9483287841067;305.55389131867474;308.6635567231717;4861.2951122087325;4870.902581948368;4884.682495637781;4907.460179380265;4910.288008238803;4735.147673418792;4752.168370535152;4768.904621907568;4824.552634209741;4834.395700537433;3601.4597810328464;3753.7747735135845;3754.624537546237;3820.2741479119018;3863.150106976461;3810.7141964352645;3815.4658347831187;3827.138893748912;3836;3839.1937693084346;3634.5275114235114;3806.7906265155407;3814.294355544233;3822.4075268333454;3823.1780167264033;3671.871484498043;3783.754055986352;3786.2995345860827;3789.2800367930004;3808.638179372969;3295.1762059485127;3333.2000319923286;3338.7996720196866;3352;3382.9323413531592;4596.839110631146;4601.102784956935;4604.332371806103;4607.608353289941;4613.884652883705;4215.852445164435;4305.913881722348;4306.6554675626185;4361.825526978917;4362.825486980517;4970.801167953277;5008.774605142778;5021.196608542616;5027.49725027498;5030;3711.9628803712158;3718.1076541630086;3721.8697345592773;3722.9441558376648;3728;5003.748313259048;5207.895842083175;5208.531232189083;5222.7803183647175;5233.371995360543;3763.397856343027;3767.7550959187565;3792.412176112707;3796.2597293527497;3802.8288727007352;3819.3698039823116;3845.192509573021;3878.9224215516097;3897.649211570972;3900.180961998012;4339.500957389941;4350.042990542076;4368.7815609219415;4372.584604462571;4394.956050439519;4795.856124316276;5037.748112594356;5039.546440820343;5046.26829109777;5106.310648062538;1301.9544315948897;1302.4204229117952;1306.4578200047065;1310.5020092364841;1317.8352705911761;862.7799911022774;867.2065060469725;915.8671992561042;946.2477330522252;946.3139224062637;4442.511323754385;4442.822287108479;4443.111377724468;4451.532589078165;4462.196804575207;5098.694078355311;5104.647268473857;5138.332016837787;5189;5207.166853303529;4212.599803018708;4401.845935392246;4415.448068991376;4427.690061695715;4477.395551600493;2730.508508468455;2737.986310068437;2753.986230068837;2757.5725762506845;2761.7100204478643;4880.487548807395;4888.193448081027;4892.951070489321;4896.192128298862;4923.359963204761;5035.420926593489;5247.344081989751;5271.894562108736;5274.5780337572905;5284.682919024871;4828.589569886529;4840.467548569654;4856.927146092741;4869.220924652039;4884.975575122102;5146.202338637517;5150.918307155539;5186.8962620746615;5195.220716892496;5204.73976301191;4838.822529861647;4960.751962401938;4988.975055124846;5001.849944501525;5072.898542029213;4838.2016967201425;4839.637027222902;4853.80584776605;4859.392575928009;4859.708417494961;3661.286049220483;3682.8710995115043;3695.7228207883977;3696.297703436446;3697.2790305890094;4974.253861920741;5136.075506408883;5138.922916156186;5143.588512918883;5176.482351764944;4947.802087916443;4965.975170124127;4967.850964471072;4983.501649834988;4984.252362145708;4970.831854514258;4981.227909674007;5024.824131155538;5032.169692000925;5040.344755181803;4661.696989695659;4678.391809064936;4844.903101938012;4906.362172917497;4949.876253093701;4894.412670479565;5005.724685142352;5020.774065167149;5022.522860328263;5032.496750324938;4848.75756212195;4858.736728450557;4859.441164266154;4862.3922009748785;4866.564363512702 07:50:13 INFO - PROCESS | 2453 | |5;yui.html;774.9147593764686;825.4964471672279;826.6019128797265;844.6536919862857;846.8010017645856;12410.689732756686;12798.232106073641;13082.411291491882;13086.672833179175;13087.280199589019;11447.542098316078;11482.138839587044;11521.539138434453;11528.884711152887;11570.305781653107;686.5262968551683;855.456784941562;871.9476831390136;874.7681864305964;875.7722992022057;841.7474757572704;877.3332267476732;877.5612193903048;878.2227728460294;880.6169316347409;891.2246345679268;892.6206362296031;893.9463632182058;894.8344556257114;895.2211575928951;574.7930744931821;593.1340243244824;885.2784980241115;885.791838917857;886.1979908183105;435.0819770284697;435.305687428551;657.9046038324416;674.0058413839547;675.5372569789661;12629.43167557462;12643.873561264361;12687.429065692066;12699.809502857464;12705;12185.939070304681;12623.116381853273;12714;12736.936315318457;12936;11026.44867756609;11099.167562432849;11206.047485963703;11313.3212007279;11419.257748246337;4166.812493437772;4170.9582904170875;4274.487061552633;4275.444192254987;4372.125574885035;42847;44083.55916440827;44189.558104418866;44204.02163805273;44241.55758442407;9992.700218993441;10346.741331466774;10594.894051059546;10638.042576168182;10672.62645807393;18307.542311442056;18309.084545772657;18327.450176494727;18351.17419716116;18376.724349134773;6728.764493242713;6732.899006514808;6902.344277293649;6919.930800692029;6952.443804495628;3764.9811750941617;3769.208466222069;3790.4314352847296;3827.349350610403;3868.729188956746;2762.875670594828;2797.5803629455336;2844.1325395754493;2844.360018995709;2848.5442329227226;517.3248910172262;522.3731522173407;528.6088294662;530.546382842664;538.3082738680777;517.4127365440178;530.9275263966765;600.8738164985402;603.1525706382539;620.3114542857425;7930.206979302139;7943.682252709942;8002.439829211899;8093.919060809434;8130.146334634896;583.483616998959;592.8814237152586;598.4142023637309;605.9212302400659;610.28901329951;3772.4529943158086;3812.4662547243406;3860.82626281818;3865.4588357629955;3883.980580097081;45629.31556026662;46895;46926.06147877091;49562.25656615154;50911.23633145506;45864.3120353195;46387.536124638995;47013.76493117512;47968.760156198994;48061;44017;44338.33492497615;44340.99999999935;44594.77702611596;44945;8048.839023219386;8161.714339998071;8252.298554622925;8283.95858020706;8498.1501849817;29444.411111778074;29668.703312966594;30518.389632207676;30687.539686904267;30718.84640576783;36489.81755091208;39417.2116557673;39555.80222098871;39688;40187;10469.31949423285;10505.42220177867;10939.343639381663;11050.778984420105;11399;11942.402879856147;12058.276503409472;12092.032637389164;12117.27296362221;12465.252084874937;11526.135539834379;11685.941570292094;11778.352190629597;12001.759964800829;12105.092118091354;10560.260781745204;10823;10905.618303359344;11050.06074483678;11232.831507527557;10487.737806554896;10544.209184311361;10717.732056698645;10723.088537474401;10749.677509674722;9228.446293222429;9779.190745720456;10060.798784024424;10161.187105031431;10231.795364092824;9019.413738107001;9672.05002474252;9870.309078364733;10061.195104391778;10160.136388407069 07:50:13 INFO - PROCESS | 2453 | __end_tp_report 07:50:13 INFO - PROCESS | 2453 | __start_cc_report 07:50:13 INFO - PROCESS | 2453 | _x_x_mozilla_cycle_collect,1042 07:50:13 INFO - PROCESS | 2453 | __end_cc_report 07:50:13 INFO - PROCESS | 2453 | __startTimestamp1462978213958__endTimestamp 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | ------- Summary: start ------- 07:50:13 INFO - PROCESS | 2453 | Number of tests: 6 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#0] dojo.html Cycles:165 Average:4722.02 Median:4845.54 stddev:486.33 (10.0%) stddev-sans-first:459.34 07:50:13 INFO - PROCESS | 2453 | Values: 2631.7 2676.4 2697.3 2711.7 2711.7 4681.5 4744.1 4749.8 4820.7 4877.3 4746.0 4746.3 4772.4 4784.4 4856.2 4391.5 4403.8 4405.0 4455.2 4462.7 4430.2 4510.2 4510.3 4546.1 4549.7 4441.2 4478.2 4485.9 4494.4 4513.9 4286.2 4293.7 4348.9 4413.1 4435.4 4287.2 4291.5 4291.8 4329.2 4329.5 4650.2 4674.9 4678.9 4683.5 4694.0 4996.1 5045.9 5072.0 5078.9 5106.1 5134.9 5142.3 5147.3 5183.8 5189.4 4790.7 4844.1 4845.5 4846.7 4848.8 5116.7 5127.2 5177.4 5185.6 5271.6 4986.9 5015.1 5019.0 5065.9 5066.0 4971.8 5084.1 5097.4 5123.3 5161.4 5033.4 5034.4 5080.0 5098.5 5110.0 5079.3 5097.5 5105.2 5121.6 5148.7 4287.6 4310.7 4383.6 4417.5 4457.0 3770.1 3790.5 3805.1 3829.5 3834.8 4374.1 4393.4 4397.2 4400.4 4445.2 5061.4 5105.7 5133.4 5149.9 5186.3 4377.5 4398.1 4403.4 4449.4 4451.7 4554.6 4570.2 4582.9 4583.4 4633.7 4740.5 4741.6 4781.1 4821.8 4852.3 5040.9 5052.0 5057.8 5122.2 5158.0 4813.2 4826.5 4827.3 4838.2 4840.5 4961.6 4985.9 5016.7 5030.0 5079.4 5094.2 5094.5 5117.6 5149.7 5234.0 4993.1 4993.8 5037.7 5041.7 5043.6 4861.5 4886.6 4892.0 4916.0 4949.8 4855.8 4919.5 4931.5 4966.9 4980.4 4944.1 5028.3 5037.7 5066.8 5086.0 4819.8 4821.3 4845.5 4851.0 4921.7 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#1] ext.html Cycles:180 Average:43349.90 Median:3528.08 stddev:72883.97 (2065.8%) stddev-sans-first:73017.69 07:50:13 INFO - PROCESS | 2453 | Values: 581.7 606.9 608.1 616.4 631.6 28109.6 42378.6 43023.8 43028.4 47427.3 43086.6 43523.1 43565.3 43599.0 43654.6 7772.3 8023.7 8026.2 8026.3 8031.8 7849.6 7952.0 7954.6 7956.2 7957.1 7987.1 8036.0 8036.2 8038.5 8039.3 7944.3 7970.2 7988.0 7993.4 7995.8 7899.7 7990.1 7993.6 7994.4 7996.8 40275.4 40431.4 40594.8 40658.8 40736.4 3350.3 3439.6 3443.5 3563.1 3719.9 29957.6 30274.7 30499.7 31515.4 31870.5 3507.4 3516.7 3539.4 3567.0 3745.2 245066.0 246287.0 246990.5 248454.0 248504.0 35834.3 37113.3 56477.4 56916.0 57025.7 34685.8 34761.5 34777.7 34812.5 34839.0 14195.3 14338.9 14348.1 14356.0 14357.7 11721.9 11811.8 11815.8 11826.8 11828.3 8248.1 8319.1 8338.2 8395.8 8402.7 3296.7 3297.5 3301.8 3324.8 3325.8 955.6 959.2 966.9 999.4 1057.9 28193.6 28356.1 28433.7 28535.7 28815.6 999.5 1077.3 1078.8 1134.6 1189.9 14930.9 15015.2 15291.0 15297.5 15517.1 306103.0 308319.0 311142.0 312261.0 314617.0 33539.8 33575.2 33696.8 33756.0 33841.3 1186.3 1213.8 1226.8 1285.5 1294.2 27151.6 29991.3 30269.0 30724.2 30806.5 43817.6 44354.6 44387.8 44409.1 44619.6 272668.0 274443.0 275346.0 275559.0 276710.0 26457.2 26849.3 27887.6 27896.3 29287.0 43704.3 43982.3 43998.8 45031.3 45128.8 37648.8 38151.2 38561.2 38713.0 39060.6 37706.8 37739.6 38003.8 38146.2 38189.8 37551.4 38703.8 39154.8 39278.0 42533.8 31816.4 34199.3 35019.5 35079.3 35113.8 26565.3 26627.3 26703.2 26732.9 27142.6 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#2] jquery.html Cycles:180 Average:24807.93 Median:4608.62 stddev:69725.11 (1512.9%) stddev-sans-first:69896.60 07:50:13 INFO - PROCESS | 2453 | Values: 388.9 391.1 391.5 395.8 403.3 16245.4 17041.1 17300.6 17545.9 17578.8 16870.5 17153.2 17280.5 17408.9 17555.4 5038.8 5211.3 5223.9 5235.1 5277.5 5031.9 5232.2 5234.5 5237.0 5237.5 5171.8 5184.6 5188.5 5190.8 5191.7 5013.0 5146.2 5191.2 5221.8 5222.3 4598.1 4607.7 4609.6 4609.7 4612.9 19627.8 19937.8 20043.7 20295.6 20608.8 1966.9 2012.3 2164.9 2169.7 2593.9 12989.8 12993.5 13078.8 13159.1 13517.8 1707.2 1729.9 1753.4 1762.0 1776.8 67641.3 68381.0 68558.7 68990.3 70351.0 9453.8 9574.4 9870.1 9878.3 9909.5 13699.3 13740.1 13786.1 13818.8 13830.9 7099.8 7165.2 7178.0 7221.5 7445.0 6677.5 6679.3 6729.0 6779.9 7026.8 6192.4 6234.5 6290.1 6330.4 6333.7 1795.9 1814.2 1828.8 1831.3 1847.6 907.2 920.1 939.0 981.6 1004.9 16349.6 16691.5 17084.3 17171.5 17243.3 890.3 912.5 921.4 959.6 1025.8 9764.2 9767.4 9833.6 10014.9 10121.7 411421.0 419489.0 426783.0 426789.0 441700.7 52447.7 56348.4 63584.4 68741.0 69555.0 912.7 919.9 942.1 964.4 971.6 15291.2 15541.7 15604.8 15901.1 16039.8 25197.0 26295.3 26836.7 31383.0 36042.6 50860.5 51931.5 52853.7 52862.2 53468.0 7355.0 7750.7 7796.0 7819.0 7945.4 10017.2 11391.4 11549.2 11670.6 11797.6 10365.9 10416.3 10452.5 10464.3 10522.8 5639.7 8886.4 9605.9 10341.4 10665.8 10689.4 10714.3 10877.1 10977.6 11072.1 7843.8 8017.0 8042.2 8048.3 8182.2 7948.0 9758.5 10191.9 10384.0 10499.7 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#3] mootools.html Cycles:180 Average:5333.34 Median:4929.67 stddev:3004.17 (60.9%) stddev-sans-first:2997.31 07:50:13 INFO - PROCESS | 2453 | Values: 1301.6 1490.4 1538.2 1709.8 1870.5 6772.3 7553.9 7737.4 7753.8 7971.3 7955.1 8114.5 8119.9 8125.7 8130.4 2281.0 2308.4 2323.2 2328.2 2329.7 2257.4 2274.4 2323.0 2325.3 2328.8 2279.4 2299.5 2304.0 2311.1 2314.5 2197.8 2202.3 2203.3 2205.6 2211.2 2102.7 2107.6 2109.7 2111.2 2112.5 7801.6 7862.5 7872.2 7875.3 8048.0 4723.3 4853.7 4858.9 4880.9 4882.7 2941.7 2957.8 2964.7 2975.9 3012.8 950.2 951.6 956.4 958.2 959.4 11121.6 11178.9 11227.2 11267.8 11290.2 4173.2 4216.1 4277.2 4297.7 4371.7 8734.0 8804.3 8806.7 8815.5 8820.6 4846.2 4871.5 4874.7 4879.5 4880.5 3819.2 4153.3 4155.5 4267.2 4269.1 3513.1 3563.2 3599.7 3631.4 3669.4 1072.4 1080.5 1089.9 1096.4 1100.8 755.8 819.5 932.0 937.7 960.9 5555.2 5620.7 5625.5 5730.6 5826.9 476.9 482.1 483.7 490.6 637.8 3705.1 3813.3 3815.7 3816.7 3829.4 9540.2 9990.1 10020.7 10140.4 10166.8 9712.4 9930.6 10090.8 10143.5 10166.8 7471.7 7573.1 7576.1 7649.9 7794.0 5598.8 5634.5 5690.7 5734.3 5788.4 9277.6 9286.3 9369.8 9374.9 9560.5 8985.7 9130.9 9152.5 9275.2 9481.9 7502.1 7691.9 7906.4 7912.6 7919.0 7744.6 7765.3 7827.5 7830.3 7879.6 7247.1 7258.8 7324.9 7436.7 7449.1 6471.6 6968.3 7147.0 7158.8 7281.6 6396.9 6496.1 6498.2 6505.7 6512.1 4675.8 4804.7 4892.3 4903.4 4977.3 4803.7 4862.6 4956.0 4973.3 4973.7 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#4] prototype.html Cycles:180 Average:4184.90 Median:4810.20 stddev:1187.90 (24.7%) stddev-sans-first:1154.87 07:50:13 INFO - PROCESS | 2453 | Values: 298.4 299.0 303.9 305.6 308.7 4861.3 4870.9 4884.7 4907.5 4910.3 4735.1 4752.2 4768.9 4824.6 4834.4 3601.5 3753.8 3754.6 3820.3 3863.2 3810.7 3815.5 3827.1 3836.0 3839.2 3634.5 3806.8 3814.3 3822.4 3823.2 3671.9 3783.8 3786.3 3789.3 3808.6 3295.2 3333.2 3338.8 3352.0 3382.9 4596.8 4601.1 4604.3 4607.6 4613.9 4215.9 4305.9 4306.7 4361.8 4362.8 4970.8 5008.8 5021.2 5027.5 5030.0 3712.0 3718.1 3721.9 3722.9 3728.0 5003.7 5207.9 5208.5 5222.8 5233.4 3763.4 3767.8 3792.4 3796.3 3802.8 3819.4 3845.2 3878.9 3897.6 3900.2 4339.5 4350.0 4368.8 4372.6 4395.0 4795.9 5037.7 5039.5 5046.3 5106.3 1302.0 1302.4 1306.5 1310.5 1317.8 862.8 867.2 915.9 946.2 946.3 4442.5 4442.8 4443.1 4451.5 4462.2 5098.7 5104.6 5138.3 5189.0 5207.2 4212.6 4401.8 4415.4 4427.7 4477.4 2730.5 2738.0 2754.0 2757.6 2761.7 4880.5 4888.2 4893.0 4896.2 4923.4 5035.4 5247.3 5271.9 5274.6 5284.7 4828.6 4840.5 4856.9 4869.2 4885.0 5146.2 5150.9 5186.9 5195.2 5204.7 4838.8 4960.8 4989.0 5001.8 5072.9 4838.2 4839.6 4853.8 4859.4 4859.7 3661.3 3682.9 3695.7 3696.3 3697.3 4974.3 5136.1 5138.9 5143.6 5176.5 4947.8 4966.0 4967.9 4983.5 4984.3 4970.8 4981.2 5024.8 5032.2 5040.3 4661.7 4678.4 4844.9 4906.4 4949.9 4894.4 5005.7 5020.8 5022.5 5032.5 4848.8 4858.7 4859.4 4862.4 4866.6 07:50:13 INFO - PROCESS | 2453 | 07:50:13 INFO - PROCESS | 2453 | [#5] yui.html Cycles:180 Average:12853.31 Median:4222.72 stddev:14162.07 (335.4%) stddev-sans-first:14172.75 07:50:13 INFO - PROCESS | 2453 | Values: 774.9 825.5 826.6 844.7 846.8 12410.7 12798.2 13082.4 13086.7 13087.3 11447.5 11482.1 11521.5 11528.9 11570.3 686.5 855.5 871.9 874.8 875.8 841.7 877.3 877.6 878.2 880.6 891.2 892.6 893.9 894.8 895.2 574.8 593.1 885.3 885.8 886.2 435.1 435.3 657.9 674.0 675.5 12629.4 12643.9 12687.4 12699.8 12705.0 12185.9 12623.1 12714.0 12736.9 12936.0 11026.4 11099.2 11206.0 11313.3 11419.3 4166.8 4171.0 4274.5 4275.4 4372.1 42847.0 44083.6 44189.6 44204.0 44241.6 9992.7 10346.7 10594.9 10638.0 10672.6 18307.5 18309.1 18327.5 18351.2 18376.7 6728.8 6732.9 6902.3 6919.9 6952.4 3765.0 3769.2 3790.4 3827.3 3868.7 2762.9 2797.6 2844.1 2844.4 2848.5 517.3 522.4 528.6 530.5 538.3 517.4 530.9 600.9 603.2 620.3 7930.2 7943.7 8002.4 8093.9 8130.1 583.5 592.9 598.4 605.9 610.3 3772.5 3812.5 3860.8 3865.5 3884.0 45629.3 46895.0 46926.1 49562.3 50911.2 45864.3 46387.5 47013.8 47968.8 48061.0 44017.0 44338.3 44341.0 44594.8 44945.0 8048.8 8161.7 8252.3 8284.0 8498.2 29444.4 29668.7 30518.4 30687.5 30718.8 36489.8 39417.2 39555.8 39688.0 40187.0 10469.3 10505.4 10939.3 11050.8 11399.0 11942.4 12058.3 12092.0 12117.3 12465.3 11526.1 11685.9 11778.4 12001.8 12105.1 10560.3 10823.0 10905.6 11050.1 11232.8 10487.7 10544.2 10717.7 10723.1 10749.7 9228.4 9779.2 10060.8 10161.2 10231.8 9019.4 9672.1 9870.3 10061.2 10160.1 07:50:13 INFO - PROCESS | 2453 | -------- Summary: end -------- 07:50:13 INFO - PROCESS | 2453 | 07:50:14 INFO - TEST-INFO | 2453: exit 0 07:50:14 INFO - TEST-OK | dromaeo_css | took 1118494ms 07:50:14 INFO - TEST-START | kraken 07:50:14 INFO - operating with platform_type : linux_ 07:50:14 INFO - Initialising browser for kraken test... 07:50:14 INFO - TEST-INFO | started process 2650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXcpcV/profile http://localhost:42558/getInfo.html) 07:50:18 INFO - PROCESS | 2650 | __metrics Screen width/height:1600/1200 07:50:18 INFO - PROCESS | 2650 | colorDepth:24 07:50:18 INFO - PROCESS | 2650 | Browser inner width/height: 1024/697 07:50:18 INFO - PROCESS | 2650 | __metrics 07:50:19 ERROR - PROCESS | 2650 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:50:20 INFO - TEST-INFO | 2650: exit 0 07:50:20 INFO - Browser initialized. 07:50:20 INFO - Running cycle 1/1 for kraken test... 07:50:20 INFO - TEST-INFO | started process 2769 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXcpcV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:32 INFO - PROCESS | 2769 | RSS: Main: 154308608 07:50:32 INFO - PROCESS | 2769 | 07:50:35 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42558/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:36 INFO - PROCESS | 2769 | RSS: Main: 209907712 07:50:36 INFO - PROCESS | 2769 | 07:50:38 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42558/tests/kraken/driver.html?testName=audio-dft) 07:50:39 INFO - PROCESS | 2769 | RSS: Main: 198287360 07:50:39 INFO - PROCESS | 2769 | 07:50:42 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42558/tests/kraken/driver.html?testName=audio-fft) 07:50:43 INFO - PROCESS | 2769 | RSS: Main: 265908224 07:50:43 INFO - PROCESS | 2769 | 07:50:45 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42558/tests/kraken/driver.html?testName=audio-oscillator) 07:50:46 INFO - PROCESS | 2769 | RSS: Main: 175513600 07:50:46 INFO - PROCESS | 2769 | 07:50:48 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42558/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:50:49 INFO - PROCESS | 2769 | RSS: Main: 179503104 07:50:49 INFO - PROCESS | 2769 | 07:50:52 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42558/tests/kraken/driver.html?testName=imaging-darkroom) 07:50:53 INFO - PROCESS | 2769 | RSS: Main: 215830528 07:50:53 INFO - PROCESS | 2769 | 07:50:56 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42558/tests/kraken/driver.html?testName=imaging-desaturate) 07:50:57 INFO - PROCESS | 2769 | RSS: Main: 213770240 07:50:57 INFO - PROCESS | 2769 | 07:51:00 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42558/tests/kraken/driver.html?testName=json-parse-financial) 07:51:01 INFO - PROCESS | 2769 | RSS: Main: 214376448 07:51:01 INFO - PROCESS | 2769 | 07:51:02 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42558/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:51:03 INFO - PROCESS | 2769 | RSS: Main: 209211392 07:51:03 INFO - PROCESS | 2769 | 07:51:05 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:51:06 INFO - PROCESS | 2769 | RSS: Main: 209137664 07:51:06 INFO - PROCESS | 2769 | 07:51:08 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:51:09 INFO - PROCESS | 2769 | RSS: Main: 212414464 07:51:09 INFO - PROCESS | 2769 | 07:51:11 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:12 INFO - PROCESS | 2769 | RSS: Main: 217108480 07:51:12 INFO - PROCESS | 2769 | 07:51:15 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:16 INFO - PROCESS | 2769 | RSS: Main: 217493504 07:51:16 INFO - PROCESS | 2769 | 07:51:17 INFO - PROCESS | 2769 | Cycle 1(1): loaded http://localhost:42558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42558/tests/kraken/driver.html?testName=ai-astar) 07:51:18 INFO - PROCESS | 2769 | RSS: Main: 263970816 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | __start_tp_report 07:51:18 INFO - PROCESS | 2769 | _x_x_mozilla_page_load 07:51:18 INFO - PROCESS | 2769 | _x_x_mozilla_page_load_details 07:51:18 INFO - PROCESS | 2769 | |i|pagename|runs| 07:51:18 INFO - PROCESS | 2769 | |0;ai-astar;114;107;112;112;113;113;112;113;112;112 07:51:18 INFO - PROCESS | 2769 | |1;audio-beat-detection;155;130;166;212;133;156;152;132;189;137 07:51:18 INFO - PROCESS | 2769 | |2;audio-dft;160;161;161;161;162;161;161;161;163;160 07:51:18 INFO - PROCESS | 2769 | |3;audio-fft;77;75;75;148;76;76;76;77;129;76 07:51:18 INFO - PROCESS | 2769 | |4;audio-oscillator;95;93;146;93;93;93;129;94;94;124 07:51:18 INFO - PROCESS | 2769 | |5;imaging-gaussian-blur;117;117;116;118;117;119;117;117;118;117 07:51:18 INFO - PROCESS | 2769 | |6;imaging-darkroom;146;146;146;146;145;145;146;146;145;146 07:51:18 INFO - PROCESS | 2769 | |7;imaging-desaturate;94;94;95;95;94;95;95;95;96;95 07:51:18 INFO - PROCESS | 2769 | |8;json-parse-financial;68;69;71;71;68;69;71;76;71;68 07:51:18 INFO - PROCESS | 2769 | |9;json-stringify-tinderbox;64;64;63;63;64;64;63;69;65;65 07:51:18 INFO - PROCESS | 2769 | |10;stanford-crypto-aes;83;81;82;82;82;88;83;84;151;83 07:51:18 INFO - PROCESS | 2769 | |11;stanford-crypto-ccm;129;131;138;134;129;125;131;127;148;134 07:51:18 INFO - PROCESS | 2769 | |12;stanford-crypto-pbkdf2;171;174;175;175;179;174;169;172;170;169 07:51:18 INFO - PROCESS | 2769 | |13;stanford-crypto-sha256-iterative;71;72;68;67;69;72;71;65;66;66 07:51:18 INFO - PROCESS | 2769 | __end_tp_report 07:51:18 INFO - PROCESS | 2769 | __start_cc_report 07:51:18 INFO - PROCESS | 2769 | _x_x_mozilla_cycle_collect,2067 07:51:18 INFO - PROCESS | 2769 | __end_cc_report 07:51:18 INFO - PROCESS | 2769 | __startTimestamp1462978278206__endTimestamp 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | ------- Summary: start ------- 07:51:18 INFO - PROCESS | 2769 | Number of tests: 14 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.89 (1.7%) stddev-sans-first:1.86 07:51:18 INFO - PROCESS | 2769 | Values: 114.0 107.0 112.0 112.0 113.0 113.0 112.0 113.0 112.0 112.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#1] audio-beat-detection Cycles:10 Average:156.20 Median:155.50 stddev:26.84 (17.3%) stddev-sans-first:28.46 07:51:18 INFO - PROCESS | 2769 | Values: 155.0 130.0 166.0 212.0 133.0 156.0 152.0 132.0 189.0 137.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 07:51:18 INFO - PROCESS | 2769 | Values: 160.0 161.0 161.0 161.0 162.0 161.0 161.0 161.0 163.0 160.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#3] audio-fft Cycles:10 Average:88.50 Median:76.00 stddev:26.74 (35.2%) stddev-sans-first:28.03 07:51:18 INFO - PROCESS | 2769 | Values: 77.0 75.0 75.0 148.0 76.0 76.0 76.0 77.0 129.0 76.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#4] audio-oscillator Cycles:10 Average:105.40 Median:93.00 stddev:19.82 (21.3%) stddev-sans-first:20.66 07:51:18 INFO - PROCESS | 2769 | Values: 95.0 93.0 146.0 93.0 93.0 93.0 129.0 94.0 94.0 124.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 07:51:18 INFO - PROCESS | 2769 | Values: 117.0 117.0 116.0 118.0 117.0 119.0 117.0 117.0 118.0 117.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 07:51:18 INFO - PROCESS | 2769 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 146.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 07:51:18 INFO - PROCESS | 2769 | Values: 94.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:71.00 stddev:2.44 (3.4%) stddev-sans-first:2.46 07:51:18 INFO - PROCESS | 2769 | Values: 68.0 69.0 71.0 71.0 68.0 69.0 71.0 76.0 71.0 68.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.00 stddev:1.78 (2.8%) stddev-sans-first:1.88 07:51:18 INFO - PROCESS | 2769 | Values: 64.0 64.0 63.0 63.0 64.0 64.0 63.0 69.0 65.0 65.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:83.00 stddev:21.55 (26.0%) stddev-sans-first:22.72 07:51:18 INFO - PROCESS | 2769 | Values: 83.0 81.0 82.0 82.0 82.0 88.0 83.0 84.0 151.0 83.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:132.50 stddev:6.59 (5.0%) stddev-sans-first:6.86 07:51:18 INFO - PROCESS | 2769 | Values: 129.0 131.0 138.0 134.0 129.0 125.0 131.0 127.0 148.0 134.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:174.00 stddev:3.19 (1.8%) stddev-sans-first:3.32 07:51:18 INFO - PROCESS | 2769 | Values: 171.0 174.0 175.0 175.0 179.0 174.0 169.0 172.0 170.0 169.0 07:51:18 INFO - PROCESS | 2769 | 07:51:18 INFO - PROCESS | 2769 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:70.00 stddev:2.67 (3.8%) stddev-sans-first:2.70 07:51:18 INFO - PROCESS | 2769 | Values: 71.0 72.0 68.0 67.0 69.0 72.0 71.0 65.0 66.0 66.0 07:51:18 INFO - PROCESS | 2769 | -------- Summary: end -------- 07:51:18 INFO - PROCESS | 2769 | 07:51:19 INFO - TEST-INFO | 2769: exit 0 07:51:19 INFO - TEST-OK | kraken | took 64497ms 07:51:19 INFO - SUITE-END | took 1182s 07:51:19 INFO - Completed test suite (00:19:43) 07:51:19 INFO - javascript benchmark 07:51:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2631.657884475018, 2676.4379480309135, 2697.312185392725, 2711.7152698966606, 2711.7423844734753, 4681.508441613632, 4744.07490539345, 4749.76251187441, 4820.710757354561, 4877.341558889549, 4745.952540474603, 4746.335513028179, 4772.4273087229585, 4784.378030855993, 4856.198727210006, 4391.516933137353, 4403.801828917698, 4404.97797511012, 4455.242608756519, 4462.665300102489, 4430.158269928719, 4510.210713125206, 4510.345999830022, 4546.113507865967, 4549.7042692224895, 4441.244988351988, 4478.171538265431, 4485.887852803673, 4494.37078808967, 4513.864584062467, 4286.249906266391, 4293.656507479394, 4348.934765978513, 4413.095315460323, 4435.401220835178, 4287.206866729665, 4291.463567054118, 4291.849785257501, 4329.177456283296, 4329.523752387235, 4650.18621741194, 4674.948136669256, 4678.929816052762, 4683.531646835323, 4693.976530117362, 4996.100701873661, 5045.940352525974, 5071.974640126776, 5078.923816142724, 5106.055379754757, 5134.948650513522, 5142.280080788692, 5147.253648220987, 5183.792648294064, 5189.403218629868, 4790.664653474259, 4844.103840789465, 4845.53967373099, 4846.733429661403, 4848.806047758121, 5116.667416617908, 5127.179651255781, 5177.378714554277, 5185.559227465707, 5271.6309858310315, 4986.90026199474, 5015.14742493777, 5019.046381187636, 5065.898682026339, 5066.03745288394, 4971.751412429437, 5084.084864724311, 5097.388313402341, 5123.3339665843205, 5161.4322424533275, 5033.395992480926, 5034.370703662043, 5079.974600126976, 5098.4646612105935, 5109.9744501278, 5079.288899554065, 5097.490250974947, 5105.183170692746, 5121.641485096082, 5148.665336753174, 4287.614114729689, 4310.676699247569, 4383.583559561836, 4417.536158703354, 4456.977715111469, 3770.1328694399895, 3790.5451345838674, 3805.1438426353848, 3829.463875057494, 3834.750741201813, 4374.103308821684, 4393.362962370502, 4397.1865204937185, 4400.427944367212, 4445.155420470101, 5061.44324124346, 5105.744712764421, 5133.383993920754, 5149.897002059938, 5186.325777648957, 4377.474703035656, 4398.098389830077, 4403.4495688039, 4449.399331090326, 4451.666125040635, 4554.567316104964, 4570.223062079454, 4582.931256031162, 4583.449986001701, 4633.698809577367, 4740.525947405232, 4741.596964258077, 4781.067691800065, 4821.831235906727, 4852.320675105418, 5040.924386134284, 5052.040112378636, 5057.848264552069, 5122.231665250242, 5158.0199762045095, 4813.205821039489, 4826.541478559531, 4827.348307978378, 4838.153323168361, 4840.540148685869, 4961.5534601886, 4985.925211121764, 5016.724080175625, 5029.974850125726, 5079.390473143247, 5094.2358646203365, 5094.490550944876, 5117.565006974449, 5149.716765577929, 5234.0, 4993.076280887902, 4993.825216117419, 5037.748112594429, 5041.722705251246, 5043.5712964398435, 4861.4895435978715, 4886.609071274361, 4891.972685735893, 4915.95084049155, 4949.777260023379, 4855.781489832895, 4919.545964824747, 4931.457539670705, 4966.925496117489, 4980.352554167935, 4944.085344211188, 5028.270900719302, 5037.748112594429, 5066.771995260148, 5086.033653605803, 4819.759012049453, 4821.252705830602, 4845.53967373099, 4851.0, 4921.655484116221], "value": 4691.411544880189, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.6887964938759, 606.9180660610816, 608.1091201389963, 616.3589866538799, 631.6178711879312, 28109.578356324644, 42378.57621423789, 43023.78488107559, 43028.354574681325, 47427.28859067117, 43086.56913430865, 43523.12953740923, 43565.34651980215, 43599.0, 43654.563454365525, 7772.300492955647, 8023.719169829057, 8026.197380261986, 8026.277635012848, 8031.799205019892, 7849.64676589552, 7951.9602401988195, 7954.642041108135, 7956.204379562027, 7957.124716281204, 7987.08148562914, 8035.959820200891, 8036.236557527026, 8038.477498962578, 8039.276465118138, 7944.285014348708, 7970.202979702041, 7988.041435027834, 7993.44045916786, 7995.840083198304, 7899.684012639545, 7990.12108668046, 7993.560354180518, 7994.400419968468, 7996.8400631987615, 40275.39586906199, 40431.393529097084, 40594.79702601468, 40658.796706016576, 40736.38895416571, 3350.329934013207, 3439.570053743282, 3443.483477478373, 3563.1092226943265, 3719.925601487982, 29957.55063674025, 30274.697253027407, 30499.69500305013, 31515.369692606248, 31870.521942170886, 3507.4037413639744, 3516.7362447816517, 3539.4159963605975, 3566.982165089184, 3745.194783121615, 245066.0, 246287.0, 246990.47571432035, 248454.0, 248504.0, 35834.28331433357, 37113.25773484569, 56477.43522564804, 56916.0, 57025.71487142538, 34685.82657086749, 34761.478577821355, 34777.65222347795, 34812.477812832825, 34839.0, 14195.290235488184, 14338.928305358406, 14348.139111653334, 14356.0, 14357.712845743026, 11721.88278117225, 11811.763764724657, 11815.822762658396, 11826.822597661041, 11828.34944078084, 8248.051474080557, 8319.084900660922, 8338.207870252438, 8395.790105247417, 8402.705905293285, 3296.7472360502857, 3297.4888892221743, 3301.7853839500353, 3324.8337583120747, 3325.783824051429, 955.5843208204453, 959.203860795538, 966.8646389505475, 999.3703966501204, 1057.8889216632297, 28193.57709634357, 28356.149315520568, 28433.715662843522, 28535.714642853305, 28815.56776648352, 999.5352161245056, 1077.272840832435, 1078.8166011778017, 1134.597217987619, 1189.940502974865, 14930.850691492946, 15015.249237538079, 15291.0, 15297.541073767805, 15517.146556939251, 306103.0, 308319.0, 311142.0, 312261.0, 314617.0, 33539.832300838825, 33575.16062098467, 33696.83151584276, 33756.0, 33841.32317353639, 1186.2976628537904, 1213.8482689663792, 1226.7791797476361, 1285.4729560879994, 1294.210531575738, 27151.59272610952, 29991.250218744706, 30269.0, 30724.231894202825, 30806.537901931937, 43817.561824382625, 44354.55645443504, 44387.77806111078, 44409.11181776411, 44619.55380446284, 272668.0, 274443.0, 275346.0, 275559.0, 276710.0, 26457.206283811516, 26849.328766780985, 27887.581686274316, 27896.302592435353, 29287.0, 43704.34443483287, 43982.340264896695, 43998.78000610104, 45031.324530132726, 45128.77435612801, 37648.81175594214, 38151.236975260894, 38561.22877542378, 38713.0, 39060.609393905695, 37706.8114659425, 37739.62260377361, 38003.80998094992, 38146.237075257784, 38189.809050954565, 37551.43672844964, 38703.80648096742, 39154.80422597869, 39278.0, 42533.78733106315, 31816.363672725955, 34199.31601367909, 35019.47470787889, 35079.298414032084, 35113.824430877685, 26565.33586660349, 26627.33431664224, 26703.19890403291, 26732.866335668197, 27142.592861107492], "value": 16879.46381496247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.9202713443743, 391.05761974301913, 391.49497148678205, 395.76452011053425, 403.298261025815, 16245.35018599256, 17041.147942602853, 17300.567485812862, 17545.859519131263, 17578.824211757877, 16870.493885183434, 17153.228104735284, 17280.48158555242, 17408.912955435237, 17555.38556150535, 5038.773255203516, 5211.258473033435, 5223.947760522403, 5235.083860324447, 5277.525022747952, 5031.949680503185, 5232.215167724853, 5234.502722241381, 5237.031149237403, 5237.502437268454, 5171.844844654647, 5184.637075404723, 5188.507091826271, 5190.7664155112925, 5191.714455704935, 5012.974935125301, 5146.150885103953, 5191.247269145991, 5221.843344699646, 5222.268882356473, 4598.103369842882, 4607.654425918069, 4609.585137337655, 4609.654275929319, 4612.86161415158, 19627.80372196274, 19937.800621993883, 20043.6993445097, 20295.579309448345, 20608.793912060988, 1966.8918209498472, 2012.3333366327731, 2164.8701077935216, 2169.7070895429074, 2593.922182334533, 12989.805152922714, 12993.545225917047, 13078.803817942739, 13159.144655597356, 13517.797233041512, 1707.2488105233658, 1729.852962498189, 1753.3863147898262, 1761.9559511012328, 1776.769020027401, 67641.32358676399, 68381.0, 68558.65720671414, 68990.31009689889, 70351.0, 9453.763655908657, 9574.425534467955, 9870.111689947938, 9878.308518403786, 9909.454979976026, 13699.315034248248, 13740.10689305192, 13786.103903246258, 13818.792718109236, 13830.861691382957, 7099.751508697171, 7165.211826699059, 7178.021121234734, 7221.53060051095, 7445.0, 6677.499187560952, 6679.298673639295, 6728.966355168192, 6779.898301525481, 7026.789196324118, 6192.380761923861, 6234.532410069263, 6290.119383286344, 6330.39861213184, 6333.65164915934, 1795.9191836367395, 1814.219885449243, 1828.7896891857606, 1831.2674930027829, 1847.5842935339442, 907.1925985872579, 920.0845159066771, 938.9624415023459, 981.5975450065439, 1004.9346792458467, 16349.591260218589, 16691.49925502237, 17084.316627335014, 17171.484855454357, 17243.341452701334, 890.2699786175274, 912.466207268742, 921.3734660430971, 959.5873774277128, 1025.779457416659, 9764.218862490983, 9767.413955162712, 9833.557489912972, 10014.899851001397, 10121.69634910954, 411421.0, 419489.0, 426783.0, 426789.0, 441700.7489775312, 52447.73776131171, 56348.43651563432, 63584.36415635784, 68741.0, 69555.0, 912.6668765900398, 919.8528235482424, 942.137943781446, 964.4068897628049, 971.6404930175739, 15291.235438228267, 15541.689166216387, 15604.765928510855, 15901.125438101015, 16039.759403609189, 25197.0, 26295.342616434744, 26836.731632683423, 31383.0, 36042.63957360393, 50860.456977151734, 51931.48068519266, 52853.735731321096, 52862.20706689326, 53468.0, 7354.96322518384, 7750.651220694969, 7795.961020194863, 7819.0, 7945.404094693036, 10017.248706346907, 11391.373474458984, 11549.249298795552, 11670.591529296435, 11797.646070617895, 10365.896341036492, 10416.270861039655, 10452.529636166542, 10464.267501274837, 10522.842157367493, 5639.7180140993605, 8886.377953543448, 9605.855912161183, 10341.37951722899, 10665.786684266426, 10689.358638481715, 10714.303570267908, 10877.075448586642, 10977.615783447542, 11072.114230861673, 7843.764687059397, 8017.0, 8042.155573664681, 8048.315893148913, 8182.181781822008, 7947.960260198662, 9758.51207439611, 10191.94904025475, 10384.0, 10499.68500944973], "value": 8309.011667053179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1301.5769874790694, 1490.426185918421, 1538.1770752647333, 1709.8119206887247, 1870.4669169286756, 6772.255051944288, 7553.924460755391, 7737.419693522994, 7753.806154846132, 7971.322437592797, 7955.085165206003, 8114.513129212251, 8119.91880081199, 8125.674973001072, 8130.3902207334395, 2280.999999999996, 2308.4228942764307, 2323.2101085630884, 2328.196772113618, 2329.685492458513, 2257.3792207143, 2274.4200228941595, 2322.9883850580723, 2325.3140323604493, 2328.813694904412, 2279.4415368234804, 2299.540091981602, 2304.0, 2311.144876395736, 2314.513952070059, 2197.7582465928735, 2202.2622421488823, 2203.2508946958033, 2205.636070048456, 2211.203966572025, 2102.6740855167473, 2107.6100921329603, 2109.683547467877, 2111.1766411099684, 2112.4824418017683, 7801.5709135997495, 7862.488938218998, 7872.212778722082, 7875.291223789886, 8048.0, 4723.267893476483, 4853.733044682541, 4858.854234372974, 4880.926196236822, 4882.658213925027, 2941.676415594283, 2957.822530648168, 2964.7331740143277, 2975.940481190364, 3012.774041946863, 950.2208189284789, 951.5908159491416, 956.3592393096573, 958.228625956105, 959.3764053365369, 11121.55513779448, 11178.944105279503, 11227.157963152715, 11267.830982535186, 11290.153238507062, 4173.207090652766, 4216.093539888939, 4277.2087163874785, 4297.67767417445, 4371.715838470489, 8734.0, 8804.339674524435, 8806.691765788166, 8815.471071735716, 8820.558972051373, 4846.248831431134, 4871.48849370811, 4874.683145595525, 4879.487653796371, 4880.463149053601, 3819.236152769457, 4153.252414565407, 4155.459790227251, 4267.210566045292, 4269.1034882674985, 3513.0865974846724, 3563.1804684922613, 3599.6760291573883, 3631.418972964366, 3669.412893936957, 1072.399456304472, 1080.5353697909818, 1089.8855620159768, 1096.4243772019595, 1100.801855665988, 755.8148253677774, 819.4632515702225, 932.0399988012358, 937.6718148648055, 960.8943016268205, 5555.2222688823595, 5620.690862002547, 5625.54995600351, 5730.598858079894, 5826.883462330729, 476.8926991426901, 482.11772456404697, 483.6952719786512, 490.6393800556588, 637.8327660381491, 3705.1292946157632, 3813.3326667833016, 3815.7138214634015, 3816.694664426839, 3829.3872980323013, 9540.189083927806, 9990.050945160197, 10020.749481263027, 10140.391576505433, 10166.796664066676, 9712.417254964725, 9930.602775889029, 10090.798184036279, 10143.543540540693, 10166.796664066826, 7471.663775130132, 7573.091229052549, 7576.090869095634, 7649.9290099386135, 7793.961030194812, 5598.804041858515, 5634.464725851038, 5690.71546422669, 5734.283214598176, 5788.363280039108, 9277.582508787256, 9286.30352723535, 9369.765755856159, 9374.906250937403, 9560.521973901137, 8985.685501007434, 9130.863037054582, 9152.4966126861, 9275.16523512887, 9481.905180948102, 7502.137254215617, 7691.88462173079, 7906.367490600853, 7912.564808935564, 7919.049714034353, 7744.612769361397, 7765.301122898966, 7827.530348179127, 7830.33442157423, 7879.645415956181, 7247.094113235846, 7258.782236532912, 7324.890126648211, 7436.665350059152, 7449.106107266946, 6471.579347342407, 6968.303169682991, 7147.035150254649, 7158.821029474305, 7281.635918204174, 6396.872062558629, 6496.058071579689, 6498.187726534184, 6505.7072431741635, 6512.05575191604, 4675.789589468414, 4804.663673542818, 4892.339534162985, 4903.387076615423, 4977.278294647328, 4803.687760295569, 4862.562369386772, 4955.950440495549, 4973.278874563241, 4973.676711013773], "value": 4228.722957295531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4389348025711, 298.9656189538203, 303.9483287841067, 305.55389131867474, 308.6635567231717, 4861.2951122087325, 4870.902581948368, 4884.682495637781, 4907.460179380265, 4910.288008238803, 4735.147673418792, 4752.168370535152, 4768.904621907568, 4824.552634209741, 4834.395700537433, 3601.4597810328464, 3753.7747735135845, 3754.624537546237, 3820.2741479119018, 3863.150106976461, 3810.7141964352645, 3815.4658347831187, 3827.138893748912, 3836.0, 3839.1937693084346, 3634.5275114235114, 3806.7906265155407, 3814.294355544233, 3822.4075268333454, 3823.1780167264033, 3671.871484498043, 3783.754055986352, 3786.2995345860827, 3789.2800367930004, 3808.638179372969, 3295.1762059485127, 3333.2000319923286, 3338.7996720196866, 3352.0, 3382.9323413531592, 4596.839110631146, 4601.102784956935, 4604.332371806103, 4607.608353289941, 4613.884652883705, 4215.852445164435, 4305.913881722348, 4306.6554675626185, 4361.825526978917, 4362.825486980517, 4970.801167953277, 5008.774605142778, 5021.196608542616, 5027.49725027498, 5030.0, 3711.9628803712158, 3718.1076541630086, 3721.8697345592773, 3722.9441558376648, 3728.0, 5003.748313259048, 5207.895842083175, 5208.531232189083, 5222.7803183647175, 5233.371995360543, 3763.397856343027, 3767.7550959187565, 3792.412176112707, 3796.2597293527497, 3802.8288727007352, 3819.3698039823116, 3845.192509573021, 3878.9224215516097, 3897.649211570972, 3900.180961998012, 4339.500957389941, 4350.042990542076, 4368.7815609219415, 4372.584604462571, 4394.956050439519, 4795.856124316276, 5037.748112594356, 5039.546440820343, 5046.26829109777, 5106.310648062538, 1301.9544315948897, 1302.4204229117952, 1306.4578200047065, 1310.5020092364841, 1317.8352705911761, 862.7799911022774, 867.2065060469725, 915.8671992561042, 946.2477330522252, 946.3139224062637, 4442.511323754385, 4442.822287108479, 4443.111377724468, 4451.532589078165, 4462.196804575207, 5098.694078355311, 5104.647268473857, 5138.332016837787, 5189.0, 5207.166853303529, 4212.599803018708, 4401.845935392246, 4415.448068991376, 4427.690061695715, 4477.395551600493, 2730.508508468455, 2737.986310068437, 2753.986230068837, 2757.5725762506845, 2761.7100204478643, 4880.487548807395, 4888.193448081027, 4892.951070489321, 4896.192128298862, 4923.359963204761, 5035.420926593489, 5247.344081989751, 5271.894562108736, 5274.5780337572905, 5284.682919024871, 4828.589569886529, 4840.467548569654, 4856.927146092741, 4869.220924652039, 4884.975575122102, 5146.202338637517, 5150.918307155539, 5186.8962620746615, 5195.220716892496, 5204.73976301191, 4838.822529861647, 4960.751962401938, 4988.975055124846, 5001.849944501525, 5072.898542029213, 4838.2016967201425, 4839.637027222902, 4853.80584776605, 4859.392575928009, 4859.708417494961, 3661.286049220483, 3682.8710995115043, 3695.7228207883977, 3696.297703436446, 3697.2790305890094, 4974.253861920741, 5136.075506408883, 5138.922916156186, 5143.588512918883, 5176.482351764944, 4947.802087916443, 4965.975170124127, 4967.850964471072, 4983.501649834988, 4984.252362145708, 4970.831854514258, 4981.227909674007, 5024.824131155538, 5032.169692000925, 5040.344755181803, 4661.696989695659, 4678.391809064936, 4844.903101938012, 4906.362172917497, 4949.876253093701, 4894.412670479565, 5005.724685142352, 5020.774065167149, 5022.522860328263, 5032.496750324938, 4848.75756212195, 4858.736728450557, 4859.441164266154, 4862.3922009748785, 4866.564363512702], "value": 3811.3377527288953, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.9147593764686, 825.4964471672279, 826.6019128797265, 844.6536919862857, 846.8010017645856, 12410.689732756686, 12798.232106073641, 13082.411291491882, 13086.672833179175, 13087.280199589019, 11447.542098316078, 11482.138839587044, 11521.539138434453, 11528.884711152887, 11570.305781653107, 686.5262968551683, 855.456784941562, 871.9476831390136, 874.7681864305964, 875.7722992022057, 841.7474757572704, 877.3332267476732, 877.5612193903048, 878.2227728460294, 880.6169316347409, 891.2246345679268, 892.6206362296031, 893.9463632182058, 894.8344556257114, 895.2211575928951, 574.7930744931821, 593.1340243244824, 885.2784980241115, 885.791838917857, 886.1979908183105, 435.0819770284697, 435.305687428551, 657.9046038324416, 674.0058413839547, 675.5372569789661, 12629.43167557462, 12643.873561264361, 12687.429065692066, 12699.809502857464, 12705.0, 12185.939070304681, 12623.116381853273, 12714.0, 12736.936315318457, 12936.0, 11026.44867756609, 11099.167562432849, 11206.047485963703, 11313.3212007279, 11419.257748246337, 4166.812493437772, 4170.9582904170875, 4274.487061552633, 4275.444192254987, 4372.125574885035, 42847.0, 44083.55916440827, 44189.558104418866, 44204.02163805273, 44241.55758442407, 9992.700218993441, 10346.741331466774, 10594.894051059546, 10638.042576168182, 10672.62645807393, 18307.542311442056, 18309.084545772657, 18327.450176494727, 18351.17419716116, 18376.724349134773, 6728.764493242713, 6732.899006514808, 6902.344277293649, 6919.930800692029, 6952.443804495628, 3764.9811750941617, 3769.208466222069, 3790.4314352847296, 3827.349350610403, 3868.729188956746, 2762.875670594828, 2797.5803629455336, 2844.1325395754493, 2844.360018995709, 2848.5442329227226, 517.3248910172262, 522.3731522173407, 528.6088294662, 530.546382842664, 538.3082738680777, 517.4127365440178, 530.9275263966765, 600.8738164985402, 603.1525706382539, 620.3114542857425, 7930.206979302139, 7943.682252709942, 8002.439829211899, 8093.919060809434, 8130.146334634896, 583.483616998959, 592.8814237152586, 598.4142023637309, 605.9212302400659, 610.28901329951, 3772.4529943158086, 3812.4662547243406, 3860.82626281818, 3865.4588357629955, 3883.980580097081, 45629.31556026662, 46895.0, 46926.06147877091, 49562.25656615154, 50911.23633145506, 45864.3120353195, 46387.536124638995, 47013.76493117512, 47968.760156198994, 48061.0, 44017.0, 44338.33492497615, 44340.99999999935, 44594.77702611596, 44945.0, 8048.839023219386, 8161.714339998071, 8252.298554622925, 8283.95858020706, 8498.1501849817, 29444.411111778074, 29668.703312966594, 30518.389632207676, 30687.539686904267, 30718.84640576783, 36489.81755091208, 39417.2116557673, 39555.80222098871, 39688.0, 40187.0, 10469.31949423285, 10505.42220177867, 10939.343639381663, 11050.778984420105, 11399.0, 11942.402879856147, 12058.276503409472, 12092.032637389164, 12117.27296362221, 12465.252084874937, 11526.135539834379, 11685.941570292094, 11778.352190629597, 12001.759964800829, 12105.092118091354, 10560.260781745204, 10823.0, 10905.618303359344, 11050.06074483678, 11232.831507527557, 10487.737806554896, 10544.209184311361, 10717.732056698645, 10723.088537474401, 10749.677509674722, 9228.446293222429, 9779.190745720456, 10060.798784024424, 10161.187105031431, 10231.795364092824, 9019.413738107001, 9672.05002474252, 9870.309078364733, 10061.195104391778, 10160.136388407069], "value": 6072.11171501025, "unit": "score"}], "name": "dromaeo_css", "value": 6331.124486601214, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 166.0, 212.0, 133.0, 156.0, 152.0, 132.0, 189.0, 137.0], "value": 156.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 161.0, 163.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 148.0, 76.0, 76.0, 76.0, 77.0, 129.0, 76.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 146.0, 93.0, 93.0, 93.0, 129.0, 94.0, 94.0, 124.0], "value": 105.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 118.0, 117.0, 119.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 71.0, 68.0, 69.0, 71.0, 76.0, 71.0, 68.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 64.0, 64.0, 63.0, 69.0, 65.0, 65.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 82.0, 82.0, 88.0, 83.0, 84.0, 151.0, 83.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 138.0, 134.0, 129.0, 125.0, 131.0, 127.0, 148.0, 134.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 175.0, 175.0, 179.0, 174.0, 169.0, 172.0, 170.0, 169.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 68.0, 67.0, 69.0, 72.0, 71.0, 65.0, 66.0, 66.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1579.6, "alertThreshold": 2.0}]} 07:51:19 INFO - Return code: 0 07:51:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:51:19 ERROR - # TBPL SUCCESS # 07:51:19 INFO - Running post-action listener: _resource_record_post_action 07:51:19 INFO - ##### 07:51:19 INFO - ##### Finished run-tests step (success) 07:51:19 INFO - ##### 07:51:19 INFO - Running post-run listener: _resource_record_post_run 07:51:20 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537190400; Read time: 12; Write time: 388000 07:51:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 241901568; Read time: 0; Write time: 219312 07:51:20 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295288832; Read time: 12; Write time: 168688 07:51:20 INFO - Running post-run listener: _upload_blobber_files 07:51:20 INFO - Blob upload gear active. 07:51:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:20 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 07:51:20 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:20 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:51:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:21 INFO - (blobuploader) - INFO - Done attempting. 07:51:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:51:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:51:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:22 INFO - (blobuploader) - INFO - Done attempting. 07:51:22 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:22 INFO - Return code: 0 07:51:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa3fd7f2fdbdfa624ec208479816597214651190ba164b57b6513bc59ed75734030020b93d7262ef4bd64d74f6a4c322b415034617d773225dae0dc0a25b6887", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8d4e386f9332c925e75d5644370f79a7f5054c966d9b56d48cef448c1d3810a356858b3a5bc6f67afece8b92a71281b4b8afe4c76cd0628708fa480f02ad367"} 07:51:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:22 INFO - Contents: 07:51:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa3fd7f2fdbdfa624ec208479816597214651190ba164b57b6513bc59ed75734030020b93d7262ef4bd64d74f6a4c322b415034617d773225dae0dc0a25b6887", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8d4e386f9332c925e75d5644370f79a7f5054c966d9b56d48cef448c1d3810a356858b3a5bc6f67afece8b92a71281b4b8afe4c76cd0628708fa480f02ad367"} 07:51:22 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:22 INFO - Copying logs to upload dir... 07:51:22 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.246051 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-11 07:51:22.467434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 07:51:22.471206) ========= 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=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa3fd7f2fdbdfa624ec208479816597214651190ba164b57b6513bc59ed75734030020b93d7262ef4bd64d74f6a4c322b415034617d773225dae0dc0a25b6887", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8d4e386f9332c925e75d5644370f79a7f5054c966d9b56d48cef448c1d3810a356858b3a5bc6f67afece8b92a71281b4b8afe4c76cd0628708fa480f02ad367"} build_url:https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/B4nbxojgT--NvWlemWFuyw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa3fd7f2fdbdfa624ec208479816597214651190ba164b57b6513bc59ed75734030020b93d7262ef4bd64d74f6a4c322b415034617d773225dae0dc0a25b6887", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8d4e386f9332c925e75d5644370f79a7f5054c966d9b56d48cef448c1d3810a356858b3a5bc6f67afece8b92a71281b4b8afe4c76cd0628708fa480f02ad367"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 07:51:22.496395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:51:22.496748) ========= 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=1f5564757af27664ca02d71f000001b0-1462976919.844219-109474644 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004667 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 07:51:22.578760) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 07:51:22.579083) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 07:51:22.579411) ========= ========= Total master_lag: 0.17 =========