builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1469550108.65 results: success (0) buildid: 20160726080104 builduid: 56aeb944d8ab49bb9d5b84805687e0ad revision: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.654775) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.655361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.655692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.667826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.668194) ========= 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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 09:21:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-07-26 09:21:48 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128071 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.823809) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.824247) ========= 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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018899 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.875939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:48.876463) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac --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 ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac --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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 09:21:49,018 truncating revision to first 12 chars 2016-07-26 09:21:49,018 Setting DEBUG logging. 2016-07-26 09:21:49,018 attempt 1/10 2016-07-26 09:21:49,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ceb63dec9267?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 09:21:49,324 unpacking tar archive at: mozilla-central-ceb63dec9267/testing/mozharness/ program finished with exit code 0 elapsedTime=0.640008 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:49.530262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:49.530745) ========= script_repo_revision: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:49.531232) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:49.531557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 09:21:49.541888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-26 09:21:49.542357) ========= /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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:21:49 INFO - MultiFileLogger online at 20160726 09:21:49 in /builds/slave/test 09:21:49 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 09:21:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:21:49 INFO - {'append_to_log': False, 09:21:49 INFO - 'base_work_dir': '/builds/slave/test', 09:21:49 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:21:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:21:49 INFO - 'branch': 'Firefox-Non-PGO', 09:21:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:49 INFO - 'config_files': ('talos/linux_config.py',), 09:21:49 INFO - 'default_actions': ('clobber', 09:21:49 INFO - 'read-buildbot-config', 09:21:49 INFO - 'download-and-extract', 09:21:49 INFO - 'populate-webroot', 09:21:49 INFO - 'create-virtualenv', 09:21:49 INFO - 'install', 09:21:49 INFO - 'run-tests'), 09:21:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:49 INFO - 'download_minidump_stackwalk': True, 09:21:49 INFO - 'download_symbols': 'ondemand', 09:21:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:49 INFO - 'installer_path': 'installer.exe', 09:21:49 INFO - 'log_level': 'info', 09:21:49 INFO - 'log_name': 'talos', 09:21:49 INFO - 'log_to_console': True, 09:21:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:21:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:21:49 INFO - 'opt_config_files': (), 09:21:49 INFO - 'pip_index': False, 09:21:49 INFO - 'sps_profile': False, 09:21:49 INFO - 'sps_profile_interval': 0, 09:21:49 INFO - 'suite': 'dromaeojs', 09:21:49 INFO - 'system_bits': '32', 09:21:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:49 INFO - 'title': 'talos-linux64-ix-018', 09:21:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:49 INFO - 'use_talos_json': True, 09:21:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:21:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:49 INFO - 'work_dir': 'build'} 09:21:49 INFO - [mozharness: 2016-07-26 16:21:49.777702Z] Running clobber step. 09:21:49 INFO - Running pre-action listener: _resource_record_pre_action 09:21:49 INFO - Running main action method: clobber 09:21:49 INFO - rmtree: /builds/slave/test/build 09:21:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:21:50 INFO - Running post-action listener: _resource_record_post_action 09:21:50 INFO - [mozharness: 2016-07-26 16:21:50.251712Z] Finished clobber step (success) 09:21:50 INFO - [mozharness: 2016-07-26 16:21:50.251813Z] Running read-buildbot-config step. 09:21:50 INFO - Running pre-action listener: _resource_record_pre_action 09:21:50 INFO - Running main action method: read_buildbot_config 09:21:50 INFO - Using buildbot properties: 09:21:50 INFO - { 09:21:50 INFO - "project": "", 09:21:50 INFO - "product": "firefox", 09:21:50 INFO - "script_repo_revision": "production", 09:21:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:21:50 INFO - "repository": "", 09:21:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:21:50 INFO - "buildid": "20160726080104", 09:21:50 INFO - "pgo_build": "False", 09:21:50 INFO - "basedir": "/builds/slave/test", 09:21:50 INFO - "buildnumber": 662, 09:21:50 INFO - "slavename": "talos-linux64-ix-018", 09:21:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:21:50 INFO - "platform": "ubuntu64_hw", 09:21:50 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:21:50 INFO - "branch": "mozilla-central", 09:21:50 INFO - "repo_path": "mozilla-central", 09:21:50 INFO - "revision": "ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac", 09:21:50 INFO - "stage_platform": "linux64", 09:21:50 INFO - "builduid": "56aeb944d8ab49bb9d5b84805687e0ad", 09:21:50 INFO - "slavebuilddir": "test" 09:21:50 INFO - } 09:21:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:21:50 INFO - Running post-action listener: _resource_record_post_action 09:21:50 INFO - [mozharness: 2016-07-26 16:21:50.254331Z] Finished read-buildbot-config step (success) 09:21:50 INFO - [mozharness: 2016-07-26 16:21:50.254420Z] Running download-and-extract step. 09:21:50 INFO - Running pre-action listener: _resource_record_pre_action 09:21:50 INFO - Running main action method: download_and_extract 09:21:50 INFO - mkdir: /builds/slave/test/build/tests 09:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:50 INFO - https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:21:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:21:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:21:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:21:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:21:51 INFO - Downloaded 1361 bytes. 09:21:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:21:51 INFO - Using the following test package requirements: 09:21:51 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:21:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:21:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:21:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'jsshell-linux-x86_64.zip'], 09:21:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:21:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:21:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:21:51 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:21:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:21:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:21:51 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:21:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:21: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')]} 09:21:51 INFO - https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:21:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:21:52 INFO - Downloaded 22742005 bytes. 09:21:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:21:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:21:53 INFO - Return code: 0 09:21:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:53 INFO - https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:21:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:21:55 INFO - Downloaded 22742005 bytes. 09:21:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:21:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:21:56 INFO - Return code: 0 09:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:56 INFO - https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:21:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:21:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:21:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:21:57 INFO - Downloaded 14413725 bytes. 09:21:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:21:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:21:57 INFO - Return code: 0 09:21: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')]} 09:21:57 INFO - https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:21:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:21:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:21:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:21:59 INFO - Downloaded 57166627 bytes. 09:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:21:59 INFO - mkdir: /builds/slave/test/properties 09:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:59 INFO - Contents: 09:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:21:59 INFO - Running post-action listener: _resource_record_post_action 09:21:59 INFO - Running post-action listener: set_extra_try_arguments 09:21:59 INFO - [mozharness: 2016-07-26 16:21:59.853135Z] Finished download-and-extract step (success) 09:21:59 INFO - [mozharness: 2016-07-26 16:21:59.853401Z] Running populate-webroot step. 09:21:59 INFO - Running pre-action listener: _resource_record_pre_action 09:21:59 INFO - Running main action method: populate_webroot 09:21:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:59 INFO - '--tppagecycles', 09:21:59 INFO - '7'], 09:21:59 INFO - 'tests': ['tsvgm']}}, 09:21:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:21:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:21:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:21:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['damp', 'tps']}, 09:21:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'tests': ['damp', 'tps']}, 09:21:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['dromaeo_dom']}, 09:21:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['basic_compositor_video']}, 09:21:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:21:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'other-e10s': {'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['a11yr', 09:21:59 INFO - 'ts_paint', 09:21:59 INFO - 'tpaint', 09:21:59 INFO - 'sessionrestore', 09:21:59 INFO - 'sessionrestore_no_auto_restore', 09:21:59 INFO - 'tabpaint']}, 09:21:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['tsvgx', 09:21:59 INFO - 'tsvgr_opacity', 09:21:59 INFO - 'tart', 09:21:59 INFO - 'tscrollx', 09:21:59 INFO - 'cart']}, 09:21:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:21:59 INFO - 'tsvgr_opacity', 09:21:59 INFO - 'tart', 09:21:59 INFO - 'tscrollx', 09:21:59 INFO - 'cart']}, 09:21:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'talos_options': ['--disable-e10s'], 09:21:59 INFO - 'tests': ['tp5o']}, 09:21:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'tests': ['tp5o']}, 09:21:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'talos_options': ['--disable-e10s', 09:21:59 INFO - '--xperf_path', 09:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:59 INFO - 'tests': ['tp5n']}, 09:21:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:59 INFO - 'talos_options': ['--xperf_path', 09:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:59 INFO - 'tests': ['tp5n']}}, 09:21:59 INFO - 'talos.zip': {'path': '', 09:21:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:59 INFO - Running post-action listener: _resource_record_post_action 09:21:59 INFO - [mozharness: 2016-07-26 16:21:59.874318Z] Finished populate-webroot step (success) 09:21:59 INFO - [mozharness: 2016-07-26 16:21:59.874407Z] Running create-virtualenv step. 09:21:59 INFO - Running pre-action listener: _resource_record_pre_action 09:21:59 INFO - Running main action method: create_virtualenv 09:21:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:21:59 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 09:21:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:21:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:59 INFO - Using real prefix '/usr' 09:21:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:22:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:22:02 INFO - Installing pip.................done. 09:22:02 INFO - Return code: 0 09:22:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22: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', 'pip>=1.5'] in /builds/slave/test/build 09:22: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 pip>=1.5 09:22:02 INFO - Using env: {'DISPLAY': ':0', 09:22:02 INFO - 'HOME': '/home/cltbld', 09:22:02 INFO - 'LANG': 'en_US.UTF-8', 09:22:02 INFO - 'LANGUAGE': 'en_US:en', 09:22:02 INFO - 'LOGNAME': 'cltbld', 09:22:02 INFO - 'MAIL': '/var/mail/cltbld', 09:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:02 INFO - 'MOZ_NO_REMOTE': '1', 09:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:02 INFO - 'NO_EM_RESTART': '1', 09:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:02 INFO - 'PWD': '/builds/slave/test', 09:22:02 INFO - 'SHELL': '/bin/bash', 09:22:02 INFO - 'SHLVL': '1', 09:22:02 INFO - 'TERM': 'linux', 09:22:02 INFO - 'TMOUT': '86400', 09:22:02 INFO - 'USER': 'cltbld', 09:22:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:02 INFO - '_': '/tools/buildbot/bin/python'} 09:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:02 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 09:22:02 INFO - Cleaning up... 09:22:02 INFO - Return code: 0 09:22:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:22: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 psutil>=3.1.1 09:22:02 INFO - Using env: {'DISPLAY': ':0', 09:22:02 INFO - 'HOME': '/home/cltbld', 09:22:02 INFO - 'LANG': 'en_US.UTF-8', 09:22:02 INFO - 'LANGUAGE': 'en_US:en', 09:22:02 INFO - 'LOGNAME': 'cltbld', 09:22:02 INFO - 'MAIL': '/var/mail/cltbld', 09:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:02 INFO - 'MOZ_NO_REMOTE': '1', 09:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:02 INFO - 'NO_EM_RESTART': '1', 09:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:02 INFO - 'PWD': '/builds/slave/test', 09:22:02 INFO - 'SHELL': '/bin/bash', 09:22:02 INFO - 'SHLVL': '1', 09:22:02 INFO - 'TERM': 'linux', 09:22:02 INFO - 'TMOUT': '86400', 09:22:02 INFO - 'USER': 'cltbld', 09:22:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:02 INFO - '_': '/tools/buildbot/bin/python'} 09:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:02 INFO - Downloading/unpacking psutil>=3.1.1 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:22:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:04 INFO - Installing collected packages: psutil 09:22:04 INFO - Running setup.py install for psutil 09:22:04 INFO - building 'psutil._psutil_linux' extension 09:22:04 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 09:22:05 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 09:22:05 INFO - building 'psutil._psutil_posix' extension 09:22:05 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 09:22:05 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 09:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:22:05 INFO - Successfully installed psutil 09:22:05 INFO - Cleaning up... 09:22:05 INFO - Return code: 0 09:22:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:22:05 INFO - Using env: {'DISPLAY': ':0', 09:22:05 INFO - 'HOME': '/home/cltbld', 09:22:05 INFO - 'LANG': 'en_US.UTF-8', 09:22:05 INFO - 'LANGUAGE': 'en_US:en', 09:22:05 INFO - 'LOGNAME': 'cltbld', 09:22:05 INFO - 'MAIL': '/var/mail/cltbld', 09:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:05 INFO - 'MOZ_NO_REMOTE': '1', 09:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:05 INFO - 'NO_EM_RESTART': '1', 09:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:05 INFO - 'PWD': '/builds/slave/test', 09:22:05 INFO - 'SHELL': '/bin/bash', 09:22:05 INFO - 'SHLVL': '1', 09:22:05 INFO - 'TERM': 'linux', 09:22:05 INFO - 'TMOUT': '86400', 09:22:05 INFO - 'USER': 'cltbld', 09:22:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:05 INFO - '_': '/tools/buildbot/bin/python'} 09:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:22:07 INFO - Installing collected packages: mozsystemmonitor 09:22:07 INFO - Running setup.py install for mozsystemmonitor 09:22:07 INFO - Successfully installed mozsystemmonitor 09:22:07 INFO - Cleaning up... 09:22:07 INFO - Return code: 0 09:22:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:22:07 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')]} 09:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:07 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')]} 09:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:07 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:07 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==2.5.1'] in /builds/slave/test/build 09:22:07 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==2.5.1 09:22:07 INFO - Using env: {'DISPLAY': ':0', 09:22:07 INFO - 'HOME': '/home/cltbld', 09:22:07 INFO - 'LANG': 'en_US.UTF-8', 09:22:07 INFO - 'LANGUAGE': 'en_US:en', 09:22:07 INFO - 'LOGNAME': 'cltbld', 09:22:07 INFO - 'MAIL': '/var/mail/cltbld', 09:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:07 INFO - 'MOZ_NO_REMOTE': '1', 09:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:07 INFO - 'NO_EM_RESTART': '1', 09:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:07 INFO - 'PWD': '/builds/slave/test', 09:22:07 INFO - 'SHELL': '/bin/bash', 09:22:07 INFO - 'SHLVL': '1', 09:22:07 INFO - 'TERM': 'linux', 09:22:07 INFO - 'TMOUT': '86400', 09:22:07 INFO - 'USER': 'cltbld', 09:22:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:07 INFO - '_': '/tools/buildbot/bin/python'} 09:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:22:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:22:10 INFO - warning: no files found matching '*.txt' 09:22:10 INFO - no previously-included directories found matching 'build' 09:22:10 INFO - no previously-included directories found matching 'dist' 09:22:10 INFO - no previously-included directories found matching '.git*' 09:22:10 INFO - Installing collected packages: jsonschema, functools32 09:22:10 INFO - Running setup.py install for functools32 09:22:10 INFO - warning: no files found matching '*.txt' 09:22:10 INFO - no previously-included directories found matching 'build' 09:22:10 INFO - no previously-included directories found matching 'dist' 09:22:10 INFO - no previously-included directories found matching '.git*' 09:22:10 INFO - Successfully installed jsonschema functools32 09:22:10 INFO - Cleaning up... 09:22:10 INFO - Return code: 0 09:22:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:22:10 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')]} 09:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:10 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')]} 09:22:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:10 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:10 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:22:10 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 functools32==3.2.3-2 09:22:10 INFO - Using env: {'DISPLAY': ':0', 09:22:10 INFO - 'HOME': '/home/cltbld', 09:22:10 INFO - 'LANG': 'en_US.UTF-8', 09:22:10 INFO - 'LANGUAGE': 'en_US:en', 09:22:10 INFO - 'LOGNAME': 'cltbld', 09:22:10 INFO - 'MAIL': '/var/mail/cltbld', 09:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:10 INFO - 'MOZ_NO_REMOTE': '1', 09:22:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:10 INFO - 'NO_EM_RESTART': '1', 09:22:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:10 INFO - 'PWD': '/builds/slave/test', 09:22:10 INFO - 'SHELL': '/bin/bash', 09:22:10 INFO - 'SHLVL': '1', 09:22:10 INFO - 'TERM': 'linux', 09:22:10 INFO - 'TMOUT': '86400', 09:22:10 INFO - 'USER': 'cltbld', 09:22:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:10 INFO - '_': '/tools/buildbot/bin/python'} 09:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:22:10 INFO - Cleaning up... 09:22:10 INFO - Return code: 0 09:22:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22:10 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')]} 09:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:10 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')]} 09:22:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:10 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 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:10 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 09:22:10 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 09:22:10 INFO - Using env: {'DISPLAY': ':0', 09:22:10 INFO - 'HOME': '/home/cltbld', 09:22:10 INFO - 'LANG': 'en_US.UTF-8', 09:22:10 INFO - 'LANGUAGE': 'en_US:en', 09:22:10 INFO - 'LOGNAME': 'cltbld', 09:22:10 INFO - 'MAIL': '/var/mail/cltbld', 09:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:10 INFO - 'MOZ_NO_REMOTE': '1', 09:22:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:10 INFO - 'NO_EM_RESTART': '1', 09:22:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:10 INFO - 'PWD': '/builds/slave/test', 09:22:10 INFO - 'SHELL': '/bin/bash', 09:22:10 INFO - 'SHLVL': '1', 09:22:10 INFO - 'TERM': 'linux', 09:22:10 INFO - 'TMOUT': '86400', 09:22:10 INFO - 'USER': 'cltbld', 09:22:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:10 INFO - '_': '/tools/buildbot/bin/python'} 09:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:13 INFO - Downloading docopt-0.6.1.tar.gz 09:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:13 INFO - Running setup.py install for blobuploader 09:22:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:13 INFO - Running setup.py install for requests 09:22:13 INFO - Running setup.py install for docopt 09:22:13 INFO - Successfully installed blobuploader requests docopt 09:22:13 INFO - Cleaning up... 09:22:14 INFO - Return code: 0 09:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:14 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')]} 09:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:14 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')]} 09:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:14 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 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:14 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 09:22:14 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 09:22:14 INFO - Using env: {'DISPLAY': ':0', 09:22:14 INFO - 'HOME': '/home/cltbld', 09:22:14 INFO - 'LANG': 'en_US.UTF-8', 09:22:14 INFO - 'LANGUAGE': 'en_US:en', 09:22:14 INFO - 'LOGNAME': 'cltbld', 09:22:14 INFO - 'MAIL': '/var/mail/cltbld', 09:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:14 INFO - 'MOZ_NO_REMOTE': '1', 09:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:14 INFO - 'NO_EM_RESTART': '1', 09:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:14 INFO - 'PWD': '/builds/slave/test', 09:22:14 INFO - 'SHELL': '/bin/bash', 09:22:14 INFO - 'SHLVL': '1', 09:22:14 INFO - 'TERM': 'linux', 09:22:14 INFO - 'TMOUT': '86400', 09:22:14 INFO - 'USER': 'cltbld', 09:22:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:14 INFO - '_': '/tools/buildbot/bin/python'} 09:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:14 INFO - Running setup.py (path:/tmp/pip-E0Yffe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:14 INFO - Running setup.py (path:/tmp/pip-qDZFGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:14 INFO - Running setup.py (path:/tmp/pip-yufByo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:14 INFO - Running setup.py (path:/tmp/pip-P0fN86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:14 INFO - Running setup.py (path:/tmp/pip-v4I7x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:14 INFO - Running setup.py (path:/tmp/pip-YzbG13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:14 INFO - Running setup.py (path:/tmp/pip-ras81k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:14 INFO - Running setup.py (path:/tmp/pip-l5Xfay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:14 INFO - Running setup.py (path:/tmp/pip-Rx585t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:14 INFO - Running setup.py (path:/tmp/pip-Ki31kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:14 INFO - Running setup.py (path:/tmp/pip-OMOOJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:15 INFO - Running setup.py (path:/tmp/pip-V6o9fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:15 INFO - Running setup.py (path:/tmp/pip-GrGoHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:15 INFO - Running setup.py (path:/tmp/pip-j9sHl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:15 INFO - Running setup.py (path:/tmp/pip-vQQLFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:15 INFO - Running setup.py (path:/tmp/pip-GEQ115-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:15 INFO - Running setup.py (path:/tmp/pip-GThvzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:22:15 INFO - Running setup.py install for manifestparser 09:22:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:22:15 INFO - Running setup.py install for mozcrash 09:22:15 INFO - Running setup.py install for mozdebug 09:22:15 INFO - Running setup.py install for mozdevice 09:22:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:22:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:22:15 INFO - Running setup.py install for mozfile 09:22:15 INFO - Running setup.py install for mozhttpd 09:22:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozinfo 09:22:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozInstall 09:22:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozleak 09:22:16 INFO - Running setup.py install for mozlog 09:22:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for moznetwork 09:22:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozprocess 09:22:16 INFO - Running setup.py install for mozprofile 09:22:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozrunner 09:22:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:22:16 INFO - Running setup.py install for mozscreenshot 09:22:17 INFO - Running setup.py install for moztest 09:22:17 INFO - Running setup.py install for mozversion 09:22:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:22:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:22:17 INFO - Cleaning up... 09:22:17 INFO - Return code: 0 09:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:17 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')]} 09:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 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')]} 09:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 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 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:17 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 09:22:17 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 09:22:17 INFO - Using env: {'DISPLAY': ':0', 09:22:17 INFO - 'HOME': '/home/cltbld', 09:22:17 INFO - 'LANG': 'en_US.UTF-8', 09:22:17 INFO - 'LANGUAGE': 'en_US:en', 09:22:17 INFO - 'LOGNAME': 'cltbld', 09:22:17 INFO - 'MAIL': '/var/mail/cltbld', 09:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:17 INFO - 'MOZ_NO_REMOTE': '1', 09:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:17 INFO - 'NO_EM_RESTART': '1', 09:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:17 INFO - 'PWD': '/builds/slave/test', 09:22:17 INFO - 'SHELL': '/bin/bash', 09:22:17 INFO - 'SHLVL': '1', 09:22:17 INFO - 'TERM': 'linux', 09:22:17 INFO - 'TMOUT': '86400', 09:22:17 INFO - 'USER': 'cltbld', 09:22:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:17 INFO - '_': '/tools/buildbot/bin/python'} 09:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:17 INFO - Running setup.py (path:/tmp/pip-neUalP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:17 INFO - Running setup.py (path:/tmp/pip-Lg7ML6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:17 INFO - Running setup.py (path:/tmp/pip-3ozHiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:17 INFO - Running setup.py (path:/tmp/pip-NhbHR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:17 INFO - Running setup.py (path:/tmp/pip-vWIyBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:17 INFO - Running setup.py (path:/tmp/pip-OiNzVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:17 INFO - Running setup.py (path:/tmp/pip-ZgtWsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:17 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)) 09:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:17 INFO - Running setup.py (path:/tmp/pip-SqLZkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:18 INFO - Running setup.py (path:/tmp/pip-Yg6rBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:18 INFO - Running setup.py (path:/tmp/pip-HfGEIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:18 INFO - Running setup.py (path:/tmp/pip-xCR6QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:18 INFO - Running setup.py (path:/tmp/pip-qnYN_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:18 INFO - Running setup.py (path:/tmp/pip-bsqbP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:18 INFO - Running setup.py (path:/tmp/pip-TKDyYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:18 INFO - Running setup.py (path:/tmp/pip-xITHbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:18 INFO - Running setup.py (path:/tmp/pip-NHuWqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:18 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)) 09:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:18 INFO - Running setup.py (path:/tmp/pip-cBOnfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:18 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)) 09:22:18 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)) 09:22:18 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)) 09:22:18 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)) 09:22:18 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)) 09:22:18 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)) 09:22:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22: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 09:22:20 INFO - Downloading blessings-1.6.tar.gz 09:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:20 INFO - Installing collected packages: blessings 09:22:20 INFO - Running setup.py install for blessings 09:22:20 INFO - Successfully installed blessings 09:22:20 INFO - Cleaning up... 09:22:20 INFO - Return code: 0 09:22:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:21 INFO - Reading from file tmpfile_stdout 09:22:21 INFO - Current package versions: 09:22:21 INFO - argparse == 1.2.1 09:22:21 INFO - blessings == 1.6 09:22:21 INFO - blobuploader == 1.2.4 09:22:21 INFO - docopt == 0.6.1 09:22:21 INFO - functools32 == 3.2.3-2 09:22:21 INFO - jsonschema == 2.5.1 09:22:21 INFO - manifestparser == 1.1 09:22:21 INFO - mozInstall == 1.12 09:22:21 INFO - mozcrash == 0.17 09:22:21 INFO - mozdebug == 0.1 09:22:21 INFO - mozdevice == 0.48 09:22:21 INFO - mozfile == 1.2 09:22:21 INFO - mozhttpd == 0.7 09:22:21 INFO - mozinfo == 0.9 09:22:21 INFO - mozleak == 0.1 09:22:21 INFO - mozlog == 3.3 09:22:21 INFO - moznetwork == 0.27 09:22:21 INFO - mozprocess == 0.23 09:22:21 INFO - mozprofile == 0.28 09:22:21 INFO - mozrunner == 6.12 09:22:21 INFO - mozscreenshot == 0.1 09:22:21 INFO - mozsystemmonitor == 0.3 09:22:21 INFO - moztest == 0.7 09:22:21 INFO - mozversion == 1.4 09:22:21 INFO - psutil == 3.1.1 09:22:21 INFO - requests == 1.2.3 09:22:21 INFO - wsgiref == 0.1.2 09:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:21 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')]} 09:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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')]} 09:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:21 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 09:22:21 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 09:22:21 INFO - Using env: {'DISPLAY': ':0', 09:22:21 INFO - 'HOME': '/home/cltbld', 09:22:21 INFO - 'LANG': 'en_US.UTF-8', 09:22:21 INFO - 'LANGUAGE': 'en_US:en', 09:22:21 INFO - 'LOGNAME': 'cltbld', 09:22:21 INFO - 'MAIL': '/var/mail/cltbld', 09:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:21 INFO - 'MOZ_NO_REMOTE': '1', 09:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:21 INFO - 'NO_EM_RESTART': '1', 09:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:21 INFO - 'PWD': '/builds/slave/test', 09:22:21 INFO - 'SHELL': '/bin/bash', 09:22:21 INFO - 'SHLVL': '1', 09:22:21 INFO - 'TERM': 'linux', 09:22:21 INFO - 'TMOUT': '86400', 09:22:21 INFO - 'USER': 'cltbld', 09:22:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:21 INFO - '_': '/tools/buildbot/bin/python'} 09:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 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)) 09:22:21 INFO - Cleaning up... 09:22:21 INFO - Return code: 0 09:22:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22:21 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')]} 09:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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')]} 09:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:21 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 0x17cd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, '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': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', '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 09:22:21 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 09:22:21 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 09:22:21 INFO - Using env: {'DISPLAY': ':0', 09:22:21 INFO - 'HOME': '/home/cltbld', 09:22:21 INFO - 'LANG': 'en_US.UTF-8', 09:22:21 INFO - 'LANGUAGE': 'en_US:en', 09:22:21 INFO - 'LOGNAME': 'cltbld', 09:22:21 INFO - 'MAIL': '/var/mail/cltbld', 09:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:21 INFO - 'MOZ_NO_REMOTE': '1', 09:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:21 INFO - 'NO_EM_RESTART': '1', 09:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:21 INFO - 'PWD': '/builds/slave/test', 09:22:21 INFO - 'SHELL': '/bin/bash', 09:22:21 INFO - 'SHLVL': '1', 09:22:21 INFO - 'TERM': 'linux', 09:22:21 INFO - 'TMOUT': '86400', 09:22:21 INFO - 'USER': 'cltbld', 09:22:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:21 INFO - '_': '/tools/buildbot/bin/python'} 09:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:22:21 INFO - Cleaning up... 09:22:21 INFO - Return code: 0 09:22:21 INFO - Running post-action listener: _resource_record_post_action 09:22:21 INFO - Running post-action listener: _start_resource_monitoring 09:22:21 INFO - Starting resource monitoring. 09:22:21 INFO - [mozharness: 2016-07-26 16:22:21.528059Z] Finished create-virtualenv step (success) 09:22:21 INFO - [mozharness: 2016-07-26 16:22:21.528541Z] Running install step. 09:22:21 INFO - Running pre-action listener: _resource_record_pre_action 09:22:21 INFO - Running main action method: install 09:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:21 INFO - Reading from file tmpfile_stdout 09:22:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:21 INFO - Reading from file tmpfile_stdout 09:22:21 INFO - Output received: 09:22:21 INFO - Usage: mozinstall [options] installer 09:22:21 INFO - Options: 09:22:21 INFO - -h, --help show this help message and exit 09:22:21 INFO - -d DEST, --destination=DEST 09:22:21 INFO - Directory to install application into. [default: 09:22:21 INFO - "/builds/slave/test"] 09:22:21 INFO - --app=APP Application being installed. [default: firefox] 09:22:21 INFO - mkdir: /builds/slave/test/build/application 09:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:22:38 INFO - Reading from file tmpfile_stdout 09:22:38 INFO - Output received: 09:22:38 INFO - /builds/slave/test/build/application/firefox/firefox 09:22:38 INFO - Running post-action listener: _resource_record_post_action 09:22:38 INFO - [mozharness: 2016-07-26 16:22:38.215478Z] Finished install step (success) 09:22:38 INFO - [mozharness: 2016-07-26 16:22:38.215816Z] Running run-tests step. 09:22:38 INFO - Running pre-action listener: _resource_record_pre_action 09:22:38 INFO - Running main action method: run_tests 09:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:38 INFO - Python 2.7.3 09:22:38 INFO - Return code: 0 09:22:38 INFO - grabbing minidump binary from tooltool 09:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:38 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 0x1a8c3b0>, '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 0x1a8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:38 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 09:22:38 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 09:22:38 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 09:22:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:38 INFO - Return code: 0 09:22:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:22:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:22:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:38 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/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 09:22:38 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/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 09:22:38 INFO - Using env: {'DISPLAY': ':0', 09:22:38 INFO - 'HOME': '/home/cltbld', 09:22:38 INFO - 'LANG': 'en_US.UTF-8', 09:22:38 INFO - 'LANGUAGE': 'en_US:en', 09:22:38 INFO - 'LOGNAME': 'cltbld', 09:22:38 INFO - 'MAIL': '/var/mail/cltbld', 09:22:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:38 INFO - 'MOZ_NO_REMOTE': '1', 09:22:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:38 INFO - 'NO_EM_RESTART': '1', 09:22:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:38 INFO - 'PWD': '/builds/slave/test', 09:22:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:38 INFO - 'SHELL': '/bin/bash', 09:22:38 INFO - 'SHLVL': '1', 09:22:38 INFO - 'TERM': 'linux', 09:22:38 INFO - 'TMOUT': '86400', 09:22:38 INFO - 'USER': 'cltbld', 09:22:38 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631', 09:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:38 INFO - '_': '/tools/buildbot/bin/python'} 09:22:38 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/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 09:22:38 INFO - mozversion application_buildid: 20160726080104 09:22:38 INFO - mozversion application_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 09:22:38 INFO - mozversion application_display_name: Nightly 09:22:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:38 INFO - mozversion application_name: Firefox 09:22:38 INFO - mozversion application_remotingname: firefox 09:22:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:22:38 INFO - mozversion application_vendor: Mozilla 09:22:38 INFO - mozversion application_version: 50.0a1 09:22:38 INFO - mozversion platform_buildid: 20160726080104 09:22:38 INFO - mozversion platform_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 09:22:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:22:38 INFO - mozversion platform_version: 50.0a1 09:22:38 INFO - using testdate: 1469550158 09:22:38 INFO - actual date: 1469550158 09:22:38 INFO - starting webserver on 'localhost:44836' 09:22:38 INFO - SUITE-START | Running 2 tests 09:22:38 INFO - TEST-START | dromaeo_css 09:22:38 INFO - operating with platform_type : linux_ 09:22:38 INFO - Initialising browser for dromaeo_css test... 09:22:38 INFO - TEST-INFO | started process 5417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoqeetQ/profile http://localhost:44836/getInfo.html) 09:22:41 INFO - PROCESS | 5417 | JavaScript error: , line 0: uncaught exception: undefined 09:22:42 INFO - PROCESS | 5417 | __metrics Screen width/height:1600/1200 09:22:42 INFO - PROCESS | 5417 | colorDepth:24 09:22:42 INFO - PROCESS | 5417 | Browser inner width/height: 1024/697 09:22:42 INFO - PROCESS | 5417 | __metrics 09:22:43 INFO - PROCESS | 5417 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:22:43 INFO - PROCESS | 5417 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:44 INFO - TEST-INFO | 5417: exit 0 09:22:44 INFO - Browser initialized. 09:22:44 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:44 INFO - TEST-INFO | started process 5496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoqeetQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:56 INFO - PROCESS | 5496 | RSS: Main: 159055872 09:22:56 INFO - PROCESS | 5496 | 09:23:47 INFO - PROCESS | 5496 | 1469550227321 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] 09:26:00 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44836/tests/dromaeo/cssquery-ext.html) 09:26:01 INFO - PROCESS | 5496 | RSS: Main: 281665536 09:26:01 INFO - PROCESS | 5496 | 09:29:04 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-ext.html (next: http://localhost:44836/tests/dromaeo/cssquery-jquery.html) 09:29:04 INFO - PROCESS | 5496 | RSS: Main: 244875264 09:29:04 INFO - PROCESS | 5496 | 09:32:07 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44836/tests/dromaeo/cssquery-mootools.html) 09:32:07 INFO - PROCESS | 5496 | RSS: Main: 191442944 09:32:07 INFO - PROCESS | 5496 | 09:35:09 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44836/tests/dromaeo/cssquery-prototype.html) 09:35:10 INFO - PROCESS | 5496 | RSS: Main: 195559424 09:35:10 INFO - PROCESS | 5496 | 09:38:13 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44836/tests/dromaeo/cssquery-yui.html) 09:38:14 INFO - PROCESS | 5496 | RSS: Main: 191078400 09:38:14 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | Cycle 1(1): loaded http://localhost:44836/tests/dromaeo/cssquery-yui.html (next: http://localhost:44836/tests/dromaeo/cssquery-dojo.html) 09:41:17 INFO - PROCESS | 5496 | RSS: Main: 286531584 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | __start_tp_report 09:41:17 INFO - PROCESS | 5496 | _x_x_mozilla_page_load 09:41:17 INFO - PROCESS | 5496 | _x_x_mozilla_page_load_details 09:41:17 INFO - PROCESS | 5496 | |i|pagename|runs| 09:41:17 INFO - PROCESS | 5496 | |0;dojo.html;2609.569421045527;2622.6459427977215;2654.920352389428;2662.7869770418356;2669.2792945904607;4612.8385506507275;4641.6054635355995;4689.859304220875;4691.5073917238715;4692.085043416535;4677.158111539921;4724.929126063112;4732.36113124728;4746.738929358884;4771.856844294668;4295.656347492193;4309.698321117523;4363.236433624119;4419.646428285745;4490.573395527436;4407.316865885793;4423.845165419211;4436.511983681792;4441.977790111045;4457.24226881431;4216.262154123016;4272.722273052242;4317.050248945227;4325.82696692132;4356.346548017791;4312.245357062501;4329.112531930947;4342.6742994275555;4350.238708226079;4366.366876802846;4360.869173924787;4366.19225443294;4366.737995720236;4408.713433626836;4410.2282100632265;4635.2120139576355;4644.349791029259;4783.856484305476;4793.808247670089;4833.589144922686;4978.352814134176;5029.119904016819;5035.597152227813;5077.593792496591;5082.466341034212;5054.368203974504;5061.114304996611;5068.4171320298265;5111.923321150186;5117.744112794346;4583.862484125415;4693.108309421199;4706.129366067288;4755.548222918852;4762.999770048259;5208.062548741263;5248.2390053442805;5275.762590683428;5275.788968441219;5305.336832895888;5027.3464449621315;5036.722980236123;5080.847574572768;5087.211482220262;5087.745612719349;4934.259861020875;5046.899062018739;5124.692518448905;5158.664686795346;5165.896682066337;5019.974900125549;5075.619328550373;5085.262636917626;5121.2574176744165;5122.308488354099;5045.621578381562;5062.265971434122;5063.468335824759;5066.392032955995;5114.33513643224;4259.936100958426;4299.763513006752;4360.106178233518;4368.453943257093;4414.050979039458;3742.4760533525327;3770.1140232045445;3804.543454785388;3809.6190380961684;3841.5582208045876;4349.456317960255;4361.6510679145595;4383.956160438354;4390.626796722315;4395.384646149541;5072.188449848007;5072.51811077947;5073.695578265316;5079.771410286546;5117.385913690381;4278.7004909656025;4313.827446902152;4323.7621930793475;4326.178026175016;4410.669199809963;4578.65660075489;4647.279671650899;4658.417697787777;4667.579917807414;4668.5564871337165;4830.130576496194;4835.975820120877;4840.733759643183;4841.201201801663;4896.583790377857;5065.240213967787;5083.771230294719;5084.339035925306;5088.0587091388215;5126.307948426915;4708.882277943078;4754.762261886823;4766.737829419415;4784.354112194809;4786.8085276588545;4965.1311020571975;4992.326035985096;4997;4997.200447928314;5023.924641130313;5049.671771334852;5052.418971818141;5053.140966035856;5065.240213967934;5085.135526960499;4800.855974320776;4848.369711937426;4881.389826271716;4882.36529251195;4960.206366981266;4895.923051264341;4901.803927842846;4909.0672772173175;4912.287718280758;4941.50584941503;4838.588719958702;4875.634327425528;4888.169011268022;4894.779734912009;4897.730624815526;4826.686265392738;4907.092187945242;4958.851234462972;4986.252062190701;5002.124628189979;4876.561109500162;4888.706677599355;4894.2658601210105;4928.753562321941;4945.7527123644395 09:41:17 INFO - PROCESS | 5496 | |1;ext.html;560.1457776890243;581.4069648958063;581.9359870414254;585.7393459910336;588.402771187245;27117.186484405476;39856.60143398565;40948.59051409485;40968.79515602425;44281.77859110708;40288.798556007256;40472;40553.391699124535;40596.797016014876;40642;7721.189275126101;7900.209979002111;7900.288973992339;7903.920960790405;7904.920950790504;7514.361279291239;9888.851667224997;10124.493775311243;10125.645602403918;10417.843732344021;10253;10344.689659310232;10347.948260258689;10348.068673819354;10348.223883208751;10131.898681013206;10176.185905127571;10181.847272290921;10198.949005254963;10205.744856378577;9967.700968970943;10040.397576145455;10044.096031357212;10054.698359049167;10063.647772327933;38712;38913.805430972665;39052.609473905184;39061.41407878884;39082.60917390818;2996.955045674317;3107;3203.7116659500525;3251.073444068429;3263.96736032639;29310.41379172426;29329.853350733323;29691.257718556993;29692.257693557618;29721.40557188844;3370.5449764281752;3373.1567108222944;3487.3548393547276;3599.406097993854;3604.3872541667715;251926;253327;254503.99999999814;255474;256942;31451;53060;53208.467915321125;53265;53335.19997200045;31947.840260799014;33684;33763.32473350568;33870.661293387246;34091.4886276706;13441.39513721885;13577.456901723821;13596.524121655892;13599.252041137832;13621.863781362257;9580.95209523948;10969.780604387868;11341.26281791681;11399;11411.657650270505;7731.420143489261;7844;7941.722039728697;7957.363410927228;7980.281774640311;3054.5418187271644;3104.2549788051156;3133.5143052826847;3140.0736782649174;3155.952660710091;814.7922279818608;895.230102112196;942.707760594218;982.1259079419323;1012.9290949633455;27933.816110578173;28463.57304640432;28499.28751781222;28852.711472885003;29142.41715165685;947.0907928388824;955.2262667239559;961.1541843177959;1028.9639862604922;1070.737669271018;14532.491362802251;14936;15271.694566108616;15454.922725386301;15622.140782257085;404063;404990;408280;408493;408628;49075.75462122667;49516.77174527155;49654.503454964986;49728.50271497311;49753.25370119451;1064.11678307005;1106.0985296982933;1165.341582006164;1193.3675152169365;1208.8368070310396;25156.742162891416;28108.43783124367;28831.279218019718;29303.560446592892;29380.559291610214;41948.37077443902;42652.57347426486;42687.786561067;42714.78642606767;42760.358594620484;306214;306224;307917;308167;308236;25311.87344063268;26261.737382625928;26467.867660661574;27249.455010900067;27278.181654550393;39819.20361592694;42201;42493.787531062146;43346;43880;35861.64138358687;36694.26611467809;37082.8145859269;39802.80098599489;41337.379939301536;36320.45519317265;36598.45102323414;36703.265934680625;36719;36848.63151368559;36186;36694.8165259172;36816.81591592025;37608.247835043694;37679.43480847844;31195.84402077975;32785;33322.83338583291;33671.663283366855;33671.663283366855;25011.499770004863;25615.359616009002;25793.142623661308;25816.8709156453;25861.870690646425 09:41:17 INFO - PROCESS | 5496 | |2;jquery.html;341.2918194745903;359.70504186567024;360.28843035005866;363.61093629816094;382.6020938224247;12817.423215955281;16121.838781612196;16427.917860410696;17788.199531021102;17979.370722024698;16778.244978975945;17248.447639712424;17338;17370.47888563342;17458;4891.217405215166;4930.506949305077;5087.720175390352;5102.132637451624;5104.617153713468;3681.8159092045425;5084.0594490019275;5173.198154286091;5647.689377084259;5939.910901336483;5903.173555702205;5912.083627037795;5915.5859089863725;5918.378570250126;5920;5157;5415.620906536544;5434.184872269152;5504.2018907258225;5899.557533185007;5315.681059136426;5341.0920143575295;5347.759350829222;5355.437679043722;5356.91964620531;11947.163698541106;12215.75568488634;12229.143959922809;12354.75290494194;12976.935115324362;1777.2091419318408;1801.135454981603;1858.1545396844404;2169.07011437096;2393.988030059856;8776.561171941377;8823.161799629024;8870.46777193364;9136;9317.254619630414;1768.6727955328176;1779.7241427578747;1780.4747599458192;1780.6616742818953;1788.3561917709615;46525.767371162925;61708.38291617071;63785.36214637887;66081;66708.6664566679;9629.32594718363;9787.85318220213;10088.394696318244;10185.74535636615;10221.591136354611;13968.441262349595;14020.789688154686;14039.227842468557;14054.297285135703;14055.718885622231;7349.669264883093;7526.24737526243;7527.134379546423;7609.478104379146;7638.656260468292;6413.903791443132;6483.092367068614;6552.541322107502;6758.222804377461;6767.695453704595;4289.6139347458875;4295.098029413858;4359.607635312837;4361.672874534423;4449.17690227309;1740.782402199725;1772.1936518883876;1818.0547513028978;1820.4447643468868;1830.6704793137096;774.295391194022;861.6897916749965;869.0527325215545;900.6532484993324;903.8192361527721;16775.748363774554;16780.412685555948;17182.74225886613;17268.222929968182;17821.01984390871;862.1938487514194;871.263782104121;878.3719640457103;911.3984770051734;940.741296143566;9361.251099912128;9753.463559504153;9982.450965196984;9988.25088118394;10150.695479135638;288760;289598;293132;419147;420065;76183.61908190534;79494;80113.19886801174;80626.5968670153;82411;928.5821380378857;929.3958926697701;963.6964356227905;980.2011360740973;984.104464936904;15580.298886549906;16119.838801611833;16453.341866325212;16490.257938392562;16754.664906701553;37160.44259336058;37562.12189390465;37748.62251377451;38049.80975095107;39029;49203;53775.73112134414;55043;55952;56924.43075569357;7302.123745150616;7580.507267027846;7686.730964416219;7705.30652241301;7722.111957125003;10641.574337026432;11644.6506604802;11802.055835532965;11870.347130907883;11973.246153384944;9818.852717209105;10261.435621040915;10281.280310378203;10316.587336506456;10358.792824143324;9390.060993900555;9597.760055998657;9657.179139773198;9666.806663866824;9870.555824987749;10085.89914100879;10160.796784064425;10284.845727313947;10316.638917637847;10729.248952573245;7364.711175544366;7607.885881711668;7654.8086297843;7771.37828973692;7811.336036436966;9998.550065246936;10013.349132306377;10207.84688229692;10843.891561084289;11011.449427528752 09:41:17 INFO - PROCESS | 5496 | |3;mootools.html;1240.2372540887357;1338.9129706569072;1387.3618135657598;1423.459085547492;1681.3442757324638;6607.570507916988;7440.144383395911;7665.8083547911265;7699.191584883577;7954.323882469997;7764.611769411535;7804.921950780505;7819.100803407596;7833.255840695125;7834.412419068564;2192.079326682793;2224.877631730254;2227.9331620051425;2228.3760547046772;2230.3420490955127;2154.1706443019393;2185.016742465889;2186.6392045312505;2187.2344679362254;2205.106931692659;2156.083664442606;2169.175713228971;2170.012644246864;2172.706684597583;2193.473566344082;2107.778683238261;2108.5888251790907;2111.5671287386126;2113.3871177358546;2113.50332671822;2071;2146.6887301341376;2147.795959383856;2150.6558950567833;2151.5696860627936;7752.612369381508;7797.259260370256;7809.3752499799875;7814.960925195394;7816.296533312029;4551.180787458256;4551.749653769041;4692.061587682477;4697.4832768395445;4700.365450664185;2806.943861122766;2873.439679262545;2876.6116574262414;2893.3779237464046;2893.4936386132345;853.3685073045964;892.3619611977402;898.9685361012398;901.535709109809;926.3414001814834;10987.230893837435;11185.552577896875;11187.384693841836;11193.10455163585;11201.215914885961;3970.4441378207075;4209.095044565433;4209.1792100540715;4300.8709738707885;4302.741835489881;8718;8718.65125394977;8722.825543489094;8733.03936566977;8734.301255899512;4701.200795864711;4718.646101542398;4724.503927087675;4724.858254252378;4725.054989002213;3709.4806727058235;4163.75017498951;4353.412289340962;4392.231359512072;4398.604125628644;3458.4639380896;3503.964960350364;3626.256617393428;3628.346897558412;3631.727620428426;1024.2113572549094;1046.0637729232446;1050.5010120192846;1074.2856000759564;1077.7144056824948;692.3526502719973;830.2859540794911;833.7040350675544;842.4313588327855;842.5787106446777;5131.512506311894;5145.151050076695;5195.804964858104;5300.681959082467;5390.21841832932;369.51039872169486;385.00284263757004;389.5364516225682;392.1275162762887;394.5994815262511;3517.542809244418;3523.224890524081;3541.1678255609913;3578.443591405313;3589.8743543976357;9772.560234789451;10034.347767395097;10126.240531960133;10142.543585538668;10184.592616295266;9268.814623707489;9673.516324183762;10065.949670251748;10078.294519383573;10088.243381746399;7335.88996165058;7490.400767938552;7512.6243687815395;7529.623518824146;7535.434842386843;5152.175651895679;5206.297149884717;5366.141417373202;5442.755076021509;5529.585281103853;8760.824783504422;8892.466452012899;9019.368644194843;9059.184673379428;9067.365284430027;9228.492432915986;9241.58412871409;9337.673181438617;9352.3453358267;9354.485503297385;6203.875922481616;6793.184817821893;7026.8945965809535;7136.179339376038;7146.499235160674;7531.3598344141365;7838.80402989907;7851.489653172525;7852.13626501073;7856.489328193649;6683.532152749261;6792.117024786746;6848.691808868513;6881.13985751781;6979.930200698132;6695.196576410862;6747.359000894907;6833.624150671562;6869.828254293682;6987;6459.935400645933;6511.120998665119;6547.70535325902;6554.508411869034;6555;4526.547345265447;4540.818367265405;4571.131485017836;4641;4645.349651048923;4517.073999830092;4524.095180963886;4556.316552517016;4568.063546972929;4772.594329482033 09:41:17 INFO - PROCESS | 5496 | |4;prototype.html;277.75002497752024;282.2984882566821;286.4071372259423;293.72389953443775;294.1015198568374;4817.675139336679;4829.903401931968;4833.250846118853;4849.466558678542;4856.125897338477;4506.324051392293;4616.33063205835;4648.163330600491;4747.216709242979;4793.664443488957;3760.458212133026;3787.867424640124;3790.5830358660523;3799.9100494727927;3802.258559580869;3775.603561626031;3789.7157713171487;3809.4095415210686;3813.523309586302;3820.2741479118877;3575.6245594212623;3594.7663401878926;3704.8885334399574;3706.518152640153;3744.9438258426144;3702.426123950792;3713.535808023997;3724.9813750931344;3728.8694895678655;3743.232637309345;3290.44062509374;3291.753118516121;3295.1103202135528;3299.3566254580364;3302.091924720697;4509.797059132314;4719.787609557578;4731.384919960383;4741.454732705714;4746.359241502422;4245.299525578307;4245.363195520666;4247.936280955788;4250.744955302692;4278.44380230568;5031.52200540948;5042.873928151789;5044.319016932704;5054.974725126351;5081.923771143436;3528.752987290891;3533.823308834548;3544.9113772155642;3561.7355838677154;3569.6965757910607;5003.3495645565845;5223.537409525339;5235.554977826928;5256.316678831766;5311.9468805311835;3551.147724546142;3563.465480177942;3569.1434055826417;3571.928561428757;3579.8365531201944;3639.872604458831;3640.6541378568995;3647.8176091195332;3648.1791596891017;3650.9817450912574;4014.6186112319283;4196.391523229176;4199.853005144866;4211.578842115764;4222.809973551136;5013.523715247045;5020.171671674206;5020.949790502122;5026.723530205801;5031.698098114051;1256.47856139701;1273.0833799664226;1277.916935399196;1278.4758249117817;1281.6667666406615;810.2464707821782;823.3039937728299;835.306695442781;842.1115722912264;844.3287586368851;4435.512093669694;4442.311441726538;4442.3336499524685;4446.155230506193;4448.488423831301;5131.230315452712;5165.483451654804;5174.016936781999;5177.896442071213;5194.714290714047;4372.759498227628;4407.294832826732;4444.155610434007;4459.44256967879;4482.529334419904;2726.291164297297;2741.7669498092487;2743.2181828178873;2745.4509098180442;2757.214193954713;4905.754712264444;4908.410990681141;4909.5090490950615;4913.631477639191;4928.531789479994;5098.668586541937;5289.233061206103;5290.761915713802;5384.115559554125;5384.40771515133;4955.776990035457;4993.950060499421;5015.27278544609;5023.246513023076;5036.395632524121;5216;5287.365516138088;5299.33758280215;5300.867478313073;5321.574274057989;4896.090524695301;5094.694318340911;5097.158968770112;5110.419238284642;5184.559312458483;4954.1825598775795;4964.131277026579;4993.725345106054;4995.425526064549;4996.325496058131;3655.5430571178604;3668.046307960003;3672.4674922135605;3682.8526858926416;3684.594694583647;5160.225966105114;5214.921776173284;5250.763715632882;5269.473052694853;5310.3448275862065;5032.773525191448;5049.8737531562;5051.87370315745;5066.82266120684;5094.337736094284;5101.311322971352;5131.204663277198;5166.405863325615;5170.1986192140275;5173.611979101507;4903.95096049035;4983.377077865267;4989.650724449254;5004.899902002013;5029;4850.490698476609;4925.45819959797;5074.31496747949;5075.2387141929;5078.5175408336145;4952.702837829742;4953.405591329064;4955.1576232039915;4966.205407134841;4970.229614409773 09:41:17 INFO - PROCESS | 5496 | |5;yui.html;619.9318075011748;701.1270967645282;702.0101656664104;737.542723511423;739.0650826704217;12604.495820167194;13287;13292.069555131146;13307.600771976857;13344.466221351146;12692.30192339421;12803.23180609164;12823.42294596743;12826.935865320684;12847.100702950775;617.3425302053321;737.6016950846537;743.1082700759097;760.8364201696634;762.0854974031157;755.04863871522;757.2919320435383;757.3486801350834;757.7916073079892;757.8257000889798;812.3947658994028;814.3444527155667;814.6659869453525;815.9918400816005;816.3713940265992;649.0653459018997;781.1134362498548;800.1478425476906;806.15353878428;806.395203597302;587.5417174603816;588.4204059001879;588.6203398807788;590.1236663554618;590.2739630254767;12231.204971676812;12245.32650704211;12251.958583520322;12257.509699612005;12271.141020128594;12303.75392492154;12486.375681215903;12496;12729.809052864215;13091.149075310075;10936.398498082603;11112.388818614972;11490.36802975836;11633;11819.527218911233;3734.066483379155;3815.866444674451;3819.102510909934;3904.6095390460728;3904.726669133162;52179.73910130425;54531;54595;60644.39355606476;61580.7683846325;10118.59525618967;10551.630692925712;10558.841617375745;10567.577296908192;10597.62908298206;18599.44201673925;18606;18610.53473663142;18616.06919654012;18625.254989800527;6735.932640673628;6757.054012438164;6761.898571521431;6828.7609933653075;7062.6821793019435;3677.889663310105;3736.925261494755;3782.319182547113;3819.987703258583;3847;2725.11433784021;2826.5053615617185;2826.689064202977;2828.660560732725;2830.1226619747945;467.525461656419;527.614841165951;540.5702466539111;541.3666010767333;562.2157090858311;487.98048078077187;565.2595100418465;577.532198918877;582.8338923406893;604.0425924908981;7821.5698136601895;8801.47191168532;8858.778530536789;8966.596503157374;9011.954940225258;454.14167223946765;481.55456203012074;508.936382952131;547.9561635069185;567.9943200568024;3908.5700572936953;4144.958550414518;4176.979115104405;4255.212785634668;4259.403683484315;91983;95218.52390738002;96936.51531742296;97327;99266;87415.56292218498;87576.56211719027;88615;91949.18431933227;92927;75673;76388;76749.6162519195;76886;77106;7663.88504172449;7735.071791385069;7843.56860372685;7976.481528700616;8094.473859199134;43781.34327985146;44517;45005;45678.77160614176;46210.537894620626;57839.710801445726;65620.67189664021;65639.690316938;68279.65860170667;68871.65564172147;8557.657693692432;8818.206361427503;9397;9456.290778191526;9634.71095867125;10042.598296068367;10154.898451015692;10273.589056437659;10536.683899482721;10817;9506.334556580972;11488.425578721197;12049.277043377426;12088.214266072677;12178;12214.57248996281;12255.387230638611;12296.815547766611;12432.564860229848;12445.25328480294;11627.767444651228;11812.76374472523;12020.519179232733;12263.632091037283;12545.435455404346;10163.695089147337;10380.377177369382;10569.207309451667;10803.405812680377;10887.346759194474;10266.384016959006;10350.896491035295;10448.373097614167;10458.529366178387;10842.512086955949 09:41:17 INFO - PROCESS | 5496 | __end_tp_report 09:41:17 INFO - PROCESS | 5496 | __start_cc_report 09:41:17 INFO - PROCESS | 5496 | _x_x_mozilla_cycle_collect,1200 09:41:17 INFO - PROCESS | 5496 | __end_cc_report 09:41:17 INFO - PROCESS | 5496 | __startTimestamp1469551277479__endTimestamp 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | ------- Summary: start ------- 09:41:17 INFO - PROCESS | 5496 | Number of tests: 6 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#0] dojo.html Cycles:165 Average:4691.86 Median:4833.59 stddev:492.02 (10.2%) stddev-sans-first:465.62 09:41:17 INFO - PROCESS | 5496 | Values: 2609.6 2622.6 2654.9 2662.8 2669.3 4612.8 4641.6 4689.9 4691.5 4692.1 4677.2 4724.9 4732.4 4746.7 4771.9 4295.7 4309.7 4363.2 4419.6 4490.6 4407.3 4423.8 4436.5 4442.0 4457.2 4216.3 4272.7 4317.1 4325.8 4356.3 4312.2 4329.1 4342.7 4350.2 4366.4 4360.9 4366.2 4366.7 4408.7 4410.2 4635.2 4644.3 4783.9 4793.8 4833.6 4978.4 5029.1 5035.6 5077.6 5082.5 5054.4 5061.1 5068.4 5111.9 5117.7 4583.9 4693.1 4706.1 4755.5 4763.0 5208.1 5248.2 5275.8 5275.8 5305.3 5027.3 5036.7 5080.8 5087.2 5087.7 4934.3 5046.9 5124.7 5158.7 5165.9 5020.0 5075.6 5085.3 5121.3 5122.3 5045.6 5062.3 5063.5 5066.4 5114.3 4259.9 4299.8 4360.1 4368.5 4414.1 3742.5 3770.1 3804.5 3809.6 3841.6 4349.5 4361.7 4384.0 4390.6 4395.4 5072.2 5072.5 5073.7 5079.8 5117.4 4278.7 4313.8 4323.8 4326.2 4410.7 4578.7 4647.3 4658.4 4667.6 4668.6 4830.1 4836.0 4840.7 4841.2 4896.6 5065.2 5083.8 5084.3 5088.1 5126.3 4708.9 4754.8 4766.7 4784.4 4786.8 4965.1 4992.3 4997.0 4997.2 5023.9 5049.7 5052.4 5053.1 5065.2 5085.1 4800.9 4848.4 4881.4 4882.4 4960.2 4895.9 4901.8 4909.1 4912.3 4941.5 4838.6 4875.6 4888.2 4894.8 4897.7 4826.7 4907.1 4958.9 4986.3 5002.1 4876.6 4888.7 4894.3 4928.8 4945.8 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#1] ext.html Cycles:180 Average:47103.01 Median:33671.66 stddev:86752.42 (257.6%) stddev-sans-first:86925.40 09:41:17 INFO - PROCESS | 5496 | Values: 560.1 581.4 581.9 585.7 588.4 27117.2 39856.6 40948.6 40968.8 44281.8 40288.8 40472.0 40553.4 40596.8 40642.0 7721.2 7900.2 7900.3 7903.9 7904.9 7514.4 9888.9 10124.5 10125.6 10417.8 10253.0 10344.7 10347.9 10348.1 10348.2 10131.9 10176.2 10181.8 10198.9 10205.7 9967.7 10040.4 10044.1 10054.7 10063.6 38712.0 38913.8 39052.6 39061.4 39082.6 2997.0 3107.0 3203.7 3251.1 3264.0 29310.4 29329.9 29691.3 29692.3 29721.4 3370.5 3373.2 3487.4 3599.4 3604.4 251926.0 253327.0 254504.0 255474.0 256942.0 31451.0 53060.0 53208.5 53265.0 53335.2 31947.8 33684.0 33763.3 33870.7 34091.5 13441.4 13577.5 13596.5 13599.3 13621.9 9581.0 10969.8 11341.3 11399.0 11411.7 7731.4 7844.0 7941.7 7957.4 7980.3 3054.5 3104.3 3133.5 3140.1 3156.0 814.8 895.2 942.7 982.1 1012.9 27933.8 28463.6 28499.3 28852.7 29142.4 947.1 955.2 961.2 1029.0 1070.7 14532.5 14936.0 15271.7 15454.9 15622.1 404063.0 404990.0 408280.0 408493.0 408628.0 49075.8 49516.8 49654.5 49728.5 49753.3 1064.1 1106.1 1165.3 1193.4 1208.8 25156.7 28108.4 28831.3 29303.6 29380.6 41948.4 42652.6 42687.8 42714.8 42760.4 306214.0 306224.0 307917.0 308167.0 308236.0 25311.9 26261.7 26467.9 27249.5 27278.2 39819.2 42201.0 42493.8 43346.0 43880.0 35861.6 36694.3 37082.8 39802.8 41337.4 36320.5 36598.5 36703.3 36719.0 36848.6 36186.0 36694.8 36816.8 37608.2 37679.4 31195.8 32785.0 33322.8 33671.7 33671.7 25011.5 25615.4 25793.1 25816.9 25861.9 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#2] jquery.html Cycles:180 Average:22734.75 Median:27066.75 stddev:57815.36 (213.6%) stddev-sans-first:57953.09 09:41:17 INFO - PROCESS | 5496 | Values: 341.3 359.7 360.3 363.6 382.6 12817.4 16121.8 16427.9 17788.2 17979.4 16778.2 17248.4 17338.0 17370.5 17458.0 4891.2 4930.5 5087.7 5102.1 5104.6 3681.8 5084.1 5173.2 5647.7 5939.9 5903.2 5912.1 5915.6 5918.4 5920.0 5157.0 5415.6 5434.2 5504.2 5899.6 5315.7 5341.1 5347.8 5355.4 5356.9 11947.2 12215.8 12229.1 12354.8 12976.9 1777.2 1801.1 1858.2 2169.1 2394.0 8776.6 8823.2 8870.5 9136.0 9317.3 1768.7 1779.7 1780.5 1780.7 1788.4 46525.8 61708.4 63785.4 66081.0 66708.7 9629.3 9787.9 10088.4 10185.7 10221.6 13968.4 14020.8 14039.2 14054.3 14055.7 7349.7 7526.2 7527.1 7609.5 7638.7 6413.9 6483.1 6552.5 6758.2 6767.7 4289.6 4295.1 4359.6 4361.7 4449.2 1740.8 1772.2 1818.1 1820.4 1830.7 774.3 861.7 869.1 900.7 903.8 16775.7 16780.4 17182.7 17268.2 17821.0 862.2 871.3 878.4 911.4 940.7 9361.3 9753.5 9982.5 9988.3 10150.7 288760.0 289598.0 293132.0 419147.0 420065.0 76183.6 79494.0 80113.2 80626.6 82411.0 928.6 929.4 963.7 980.2 984.1 15580.3 16119.8 16453.3 16490.3 16754.7 37160.4 37562.1 37748.6 38049.8 39029.0 49203.0 53775.7 55043.0 55952.0 56924.4 7302.1 7580.5 7686.7 7705.3 7722.1 10641.6 11644.7 11802.1 11870.3 11973.2 9818.9 10261.4 10281.3 10316.6 10358.8 9390.1 9597.8 9657.2 9666.8 9870.6 10085.9 10160.8 10284.8 10316.6 10729.2 7364.7 7607.9 7654.8 7771.4 7811.3 9998.6 10013.3 10207.8 10843.9 11011.4 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#3] mootools.html Cycles:180 Average:5174.94 Median:4700.78 stddev:2979.04 (63.4%) stddev-sans-first:2972.72 09:41:17 INFO - PROCESS | 5496 | Values: 1240.2 1338.9 1387.4 1423.5 1681.3 6607.6 7440.1 7665.8 7699.2 7954.3 7764.6 7804.9 7819.1 7833.3 7834.4 2192.1 2224.9 2227.9 2228.4 2230.3 2154.2 2185.0 2186.6 2187.2 2205.1 2156.1 2169.2 2170.0 2172.7 2193.5 2107.8 2108.6 2111.6 2113.4 2113.5 2071.0 2146.7 2147.8 2150.7 2151.6 7752.6 7797.3 7809.4 7815.0 7816.3 4551.2 4551.7 4692.1 4697.5 4700.4 2806.9 2873.4 2876.6 2893.4 2893.5 853.4 892.4 899.0 901.5 926.3 10987.2 11185.6 11187.4 11193.1 11201.2 3970.4 4209.1 4209.2 4300.9 4302.7 8718.0 8718.7 8722.8 8733.0 8734.3 4701.2 4718.6 4724.5 4724.9 4725.1 3709.5 4163.8 4353.4 4392.2 4398.6 3458.5 3504.0 3626.3 3628.3 3631.7 1024.2 1046.1 1050.5 1074.3 1077.7 692.4 830.3 833.7 842.4 842.6 5131.5 5145.2 5195.8 5300.7 5390.2 369.5 385.0 389.5 392.1 394.6 3517.5 3523.2 3541.2 3578.4 3589.9 9772.6 10034.3 10126.2 10142.5 10184.6 9268.8 9673.5 10065.9 10078.3 10088.2 7335.9 7490.4 7512.6 7529.6 7535.4 5152.2 5206.3 5366.1 5442.8 5529.6 8760.8 8892.5 9019.4 9059.2 9067.4 9228.5 9241.6 9337.7 9352.3 9354.5 6203.9 6793.2 7026.9 7136.2 7146.5 7531.4 7838.8 7851.5 7852.1 7856.5 6683.5 6792.1 6848.7 6881.1 6979.9 6695.2 6747.4 6833.6 6869.8 6987.0 6459.9 6511.1 6547.7 6554.5 6555.0 4526.5 4540.8 4571.1 4641.0 4645.3 4517.1 4524.1 4556.3 4568.1 4772.6 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#4] prototype.html Cycles:180 Average:4186.64 Median:4823.79 stddev:1231.29 (25.5%) stddev-sans-first:1199.28 09:41:17 INFO - PROCESS | 5496 | Values: 277.8 282.3 286.4 293.7 294.1 4817.7 4829.9 4833.3 4849.5 4856.1 4506.3 4616.3 4648.2 4747.2 4793.7 3760.5 3787.9 3790.6 3799.9 3802.3 3775.6 3789.7 3809.4 3813.5 3820.3 3575.6 3594.8 3704.9 3706.5 3744.9 3702.4 3713.5 3725.0 3728.9 3743.2 3290.4 3291.8 3295.1 3299.4 3302.1 4509.8 4719.8 4731.4 4741.5 4746.4 4245.3 4245.4 4247.9 4250.7 4278.4 5031.5 5042.9 5044.3 5055.0 5081.9 3528.8 3533.8 3544.9 3561.7 3569.7 5003.3 5223.5 5235.6 5256.3 5311.9 3551.1 3563.5 3569.1 3571.9 3579.8 3639.9 3640.7 3647.8 3648.2 3651.0 4014.6 4196.4 4199.9 4211.6 4222.8 5013.5 5020.2 5020.9 5026.7 5031.7 1256.5 1273.1 1277.9 1278.5 1281.7 810.2 823.3 835.3 842.1 844.3 4435.5 4442.3 4442.3 4446.2 4448.5 5131.2 5165.5 5174.0 5177.9 5194.7 4372.8 4407.3 4444.2 4459.4 4482.5 2726.3 2741.8 2743.2 2745.5 2757.2 4905.8 4908.4 4909.5 4913.6 4928.5 5098.7 5289.2 5290.8 5384.1 5384.4 4955.8 4994.0 5015.3 5023.2 5036.4 5216.0 5287.4 5299.3 5300.9 5321.6 4896.1 5094.7 5097.2 5110.4 5184.6 4954.2 4964.1 4993.7 4995.4 4996.3 3655.5 3668.0 3672.5 3682.9 3684.6 5160.2 5214.9 5250.8 5269.5 5310.3 5032.8 5049.9 5051.9 5066.8 5094.3 5101.3 5131.2 5166.4 5170.2 5173.6 4904.0 4983.4 4989.7 5004.9 5029.0 4850.5 4925.5 5074.3 5075.2 5078.5 4952.7 4953.4 4955.2 4966.2 4970.2 09:41:17 INFO - PROCESS | 5496 | 09:41:17 INFO - PROCESS | 5496 | [#5] yui.html Cycles:180 Average:17806.90 Median:474.54 stddev:25673.89 (5410.3%) stddev-sans-first:25713.48 09:41:17 INFO - PROCESS | 5496 | Values: 619.9 701.1 702.0 737.5 739.1 12604.5 13287.0 13292.1 13307.6 13344.5 12692.3 12803.2 12823.4 12826.9 12847.1 617.3 737.6 743.1 760.8 762.1 755.0 757.3 757.3 757.8 757.8 812.4 814.3 814.7 816.0 816.4 649.1 781.1 800.1 806.2 806.4 587.5 588.4 588.6 590.1 590.3 12231.2 12245.3 12252.0 12257.5 12271.1 12303.8 12486.4 12496.0 12729.8 13091.1 10936.4 11112.4 11490.4 11633.0 11819.5 3734.1 3815.9 3819.1 3904.6 3904.7 52179.7 54531.0 54595.0 60644.4 61580.8 10118.6 10551.6 10558.8 10567.6 10597.6 18599.4 18606.0 18610.5 18616.1 18625.3 6735.9 6757.1 6761.9 6828.8 7062.7 3677.9 3736.9 3782.3 3820.0 3847.0 2725.1 2826.5 2826.7 2828.7 2830.1 467.5 527.6 540.6 541.4 562.2 488.0 565.3 577.5 582.8 604.0 7821.6 8801.5 8858.8 8966.6 9012.0 454.1 481.6 508.9 548.0 568.0 3908.6 4145.0 4177.0 4255.2 4259.4 91983.0 95218.5 96936.5 97327.0 99266.0 87415.6 87576.6 88615.0 91949.2 92927.0 75673.0 76388.0 76749.6 76886.0 77106.0 7663.9 7735.1 7843.6 7976.5 8094.5 43781.3 44517.0 45005.0 45678.8 46210.5 57839.7 65620.7 65639.7 68279.7 68871.7 8557.7 8818.2 9397.0 9456.3 9634.7 10042.6 10154.9 10273.6 10536.7 10817.0 9506.3 11488.4 12049.3 12088.2 12178.0 12214.6 12255.4 12296.8 12432.6 12445.3 11627.8 11812.8 12020.5 12263.6 12545.4 10163.7 10380.4 10569.2 10803.4 10887.3 10266.4 10350.9 10448.4 10458.5 10842.5 09:41:17 INFO - PROCESS | 5496 | -------- Summary: end -------- 09:41:17 INFO - PROCESS | 5496 | 09:41:18 INFO - TEST-INFO | 5496: exit 0 09:41:18 INFO - TEST-OK | dromaeo_css | took 1119837ms 09:41:18 INFO - TEST-START | kraken 09:41:18 INFO - operating with platform_type : linux_ 09:41:18 INFO - Initialising browser for kraken test... 09:41:18 INFO - TEST-INFO | started process 5681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbuyhPC/profile http://localhost:44836/getInfo.html) 09:41:21 INFO - PROCESS | 5681 | JavaScript error: , line 0: uncaught exception: undefined 09:41:22 INFO - PROCESS | 5681 | __metrics Screen width/height:1600/1200 09:41:22 INFO - PROCESS | 5681 | colorDepth:24 09:41:22 INFO - PROCESS | 5681 | Browser inner width/height: 1024/697 09:41:22 INFO - PROCESS | 5681 | __metrics 09:41:23 INFO - PROCESS | 5681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:41:23 INFO - PROCESS | 5681 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:24 INFO - TEST-INFO | 5681: exit 0 09:41:24 INFO - Browser initialized. 09:41:24 INFO - Running cycle 1/1 for kraken test... 09:41:24 INFO - TEST-INFO | started process 5762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbuyhPC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:36 INFO - PROCESS | 5762 | RSS: Main: 159412224 09:41:36 INFO - PROCESS | 5762 | 09:41:38 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44836/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:39 INFO - PROCESS | 5762 | RSS: Main: 215564288 09:41:39 INFO - PROCESS | 5762 | 09:41:42 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44836/tests/kraken/driver.html?testName=audio-dft) 09:41:42 INFO - PROCESS | 5762 | RSS: Main: 192843776 09:41:42 INFO - PROCESS | 5762 | 09:41:46 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44836/tests/kraken/driver.html?testName=audio-fft) 09:41:46 INFO - PROCESS | 5762 | RSS: Main: 272580608 09:41:46 INFO - PROCESS | 5762 | 09:41:49 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44836/tests/kraken/driver.html?testName=audio-oscillator) 09:41:49 INFO - PROCESS | 5762 | RSS: Main: 182284288 09:41:49 INFO - PROCESS | 5762 | 09:41:51 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44836/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:52 INFO - PROCESS | 5762 | RSS: Main: 185974784 09:41:52 INFO - PROCESS | 5762 | 09:41:55 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44836/tests/kraken/driver.html?testName=imaging-darkroom) 09:41:56 INFO - PROCESS | 5762 | RSS: Main: 222601216 09:41:56 INFO - PROCESS | 5762 | 09:41:59 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44836/tests/kraken/driver.html?testName=imaging-desaturate) 09:42:00 INFO - PROCESS | 5762 | RSS: Main: 216420352 09:42:00 INFO - PROCESS | 5762 | 09:42:03 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44836/tests/kraken/driver.html?testName=json-parse-financial) 09:42:03 INFO - PROCESS | 5762 | RSS: Main: 219934720 09:42:03 INFO - PROCESS | 5762 | 09:42:05 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44836/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:42:06 INFO - PROCESS | 5762 | RSS: Main: 185556992 09:42:06 INFO - PROCESS | 5762 | 09:42:08 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:42:08 INFO - PROCESS | 5762 | RSS: Main: 191082496 09:42:08 INFO - PROCESS | 5762 | 09:42:10 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:11 INFO - PROCESS | 5762 | RSS: Main: 195342336 09:42:11 INFO - PROCESS | 5762 | 09:42:14 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:15 INFO - PROCESS | 5762 | RSS: Main: 189358080 09:42:15 INFO - PROCESS | 5762 | 09:42:17 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:18 INFO - PROCESS | 5762 | RSS: Main: 193974272 09:42:18 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | Cycle 1(1): loaded http://localhost:44836/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44836/tests/kraken/driver.html?testName=ai-astar) 09:42:20 INFO - PROCESS | 5762 | RSS: Main: 245051392 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | __start_tp_report 09:42:20 INFO - PROCESS | 5762 | _x_x_mozilla_page_load 09:42:20 INFO - PROCESS | 5762 | _x_x_mozilla_page_load_details 09:42:20 INFO - PROCESS | 5762 | |i|pagename|runs| 09:42:20 INFO - PROCESS | 5762 | |0;ai-astar;105;113;112;113;114;113;112;114;113;112 09:42:20 INFO - PROCESS | 5762 | |1;audio-beat-detection;151;126;123;188;121;122;144;120;154;127 09:42:20 INFO - PROCESS | 5762 | |2;audio-dft;161;161;162;161;161;164;161;162;160;162 09:42:20 INFO - PROCESS | 5762 | |3;audio-fft;75;76;75;122;76;76;75;74;106;75 09:42:20 INFO - PROCESS | 5762 | |4;audio-oscillator;85;85;120;86;85;85;126;89;85;120 09:42:20 INFO - PROCESS | 5762 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;116;117 09:42:20 INFO - PROCESS | 5762 | |6;imaging-darkroom;147;148;146;147;148;147;147;147;146;146 09:42:20 INFO - PROCESS | 5762 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;94;94 09:42:20 INFO - PROCESS | 5762 | |8;json-parse-financial;62;62;60;61;73;62;63;62;65;61 09:42:20 INFO - PROCESS | 5762 | |9;json-stringify-tinderbox;65;65;65;65;67;65;66;66;65;65 09:42:20 INFO - PROCESS | 5762 | |10;stanford-crypto-aes;80;79;78;78;90;77;78;79;79;78 09:42:20 INFO - PROCESS | 5762 | |11;stanford-crypto-ccm;139;130;129;130;128;139;128;129;226;127 09:42:20 INFO - PROCESS | 5762 | |12;stanford-crypto-pbkdf2;170;167;165;166;167;170;165;171;172;168 09:42:20 INFO - PROCESS | 5762 | |13;stanford-crypto-sha256-iterative;72;69;67;68;68;67;68;67;66;67 09:42:20 INFO - PROCESS | 5762 | __end_tp_report 09:42:20 INFO - PROCESS | 5762 | __start_cc_report 09:42:20 INFO - PROCESS | 5762 | _x_x_mozilla_cycle_collect,1936 09:42:20 INFO - PROCESS | 5762 | __end_cc_report 09:42:20 INFO - PROCESS | 5762 | __startTimestamp1469551340486__endTimestamp 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | ------- Summary: start ------- 09:42:20 INFO - PROCESS | 5762 | Number of tests: 14 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.60 (2.3%) stddev-sans-first:0.78 09:42:20 INFO - PROCESS | 5762 | Values: 105.0 113.0 112.0 113.0 114.0 113.0 112.0 114.0 113.0 112.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:135.50 stddev:21.90 (16.2%) stddev-sans-first:22.69 09:42:20 INFO - PROCESS | 5762 | Values: 151.0 126.0 123.0 188.0 121.0 122.0 144.0 120.0 154.0 127.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 09:42:20 INFO - PROCESS | 5762 | Values: 161.0 161.0 162.0 161.0 161.0 164.0 161.0 162.0 160.0 162.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#3] audio-fft Cycles:10 Average:83.00 Median:75.00 stddev:16.78 (22.4%) stddev-sans-first:17.55 09:42:20 INFO - PROCESS | 5762 | Values: 75.0 76.0 75.0 122.0 76.0 76.0 75.0 74.0 106.0 75.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:85.00 stddev:17.65 (20.8%) stddev-sans-first:18.21 09:42:20 INFO - PROCESS | 5762 | Values: 85.0 85.0 120.0 86.0 85.0 85.0 126.0 89.0 85.0 120.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:42:20 INFO - PROCESS | 5762 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:42:20 INFO - PROCESS | 5762 | Values: 147.0 148.0 146.0 147.0 148.0 147.0 147.0 147.0 146.0 146.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:42:20 INFO - PROCESS | 5762 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 94.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:62.00 stddev:3.73 (6.0%) stddev-sans-first:3.93 09:42:20 INFO - PROCESS | 5762 | Values: 62.0 62.0 60.0 61.0 73.0 62.0 63.0 62.0 65.0 61.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:42:20 INFO - PROCESS | 5762 | Values: 65.0 65.0 65.0 65.0 67.0 65.0 66.0 66.0 65.0 65.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:3.75 (4.7%) stddev-sans-first:3.97 09:42:20 INFO - PROCESS | 5762 | Values: 80.0 79.0 78.0 78.0 90.0 77.0 78.0 79.0 79.0 78.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:130.00 stddev:30.36 (23.4%) stddev-sans-first:32.19 09:42:20 INFO - PROCESS | 5762 | Values: 139.0 130.0 129.0 130.0 128.0 139.0 128.0 129.0 226.0 127.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:169.00 stddev:2.51 (1.5%) stddev-sans-first:2.57 09:42:20 INFO - PROCESS | 5762 | Values: 170.0 167.0 165.0 166.0 167.0 170.0 165.0 171.0 172.0 168.0 09:42:20 INFO - PROCESS | 5762 | 09:42:20 INFO - PROCESS | 5762 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.66 (2.4%) stddev-sans-first:0.88 09:42:20 INFO - PROCESS | 5762 | Values: 72.0 69.0 67.0 68.0 68.0 67.0 68.0 67.0 66.0 67.0 09:42:20 INFO - PROCESS | 5762 | -------- Summary: end -------- 09:42:20 INFO - PROCESS | 5762 | 09:42:21 INFO - TEST-INFO | 5762: exit 0 09:42:21 INFO - TEST-OK | kraken | took 63272ms 09:42:21 INFO - SUITE-END | took 1183s 09:42:21 INFO - Completed test suite (00:19:43) 09:42:21 INFO - javascript benchmark 09:42:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.569421045527, 2622.6459427977215, 2654.920352389428, 2662.7869770418356, 2669.2792945904607, 4612.8385506507275, 4641.6054635355995, 4689.859304220875, 4691.5073917238715, 4692.085043416535, 4677.158111539921, 4724.929126063112, 4732.36113124728, 4746.738929358884, 4771.856844294668, 4295.656347492193, 4309.698321117523, 4363.236433624119, 4419.646428285745, 4490.573395527436, 4407.316865885793, 4423.845165419211, 4436.511983681792, 4441.977790111045, 4457.24226881431, 4216.262154123016, 4272.722273052242, 4317.050248945227, 4325.82696692132, 4356.346548017791, 4312.245357062501, 4329.112531930947, 4342.6742994275555, 4350.238708226079, 4366.366876802846, 4360.869173924787, 4366.19225443294, 4366.737995720236, 4408.713433626836, 4410.2282100632265, 4635.2120139576355, 4644.349791029259, 4783.856484305476, 4793.808247670089, 4833.589144922686, 4978.352814134176, 5029.119904016819, 5035.597152227813, 5077.593792496591, 5082.466341034212, 5054.368203974504, 5061.114304996611, 5068.4171320298265, 5111.923321150186, 5117.744112794346, 4583.862484125415, 4693.108309421199, 4706.129366067288, 4755.548222918852, 4762.999770048259, 5208.062548741263, 5248.2390053442805, 5275.762590683428, 5275.788968441219, 5305.336832895888, 5027.3464449621315, 5036.722980236123, 5080.847574572768, 5087.211482220262, 5087.745612719349, 4934.259861020875, 5046.899062018739, 5124.692518448905, 5158.664686795346, 5165.896682066337, 5019.974900125549, 5075.619328550373, 5085.262636917626, 5121.2574176744165, 5122.308488354099, 5045.621578381562, 5062.265971434122, 5063.468335824759, 5066.392032955995, 5114.33513643224, 4259.936100958426, 4299.763513006752, 4360.106178233518, 4368.453943257093, 4414.050979039458, 3742.4760533525327, 3770.1140232045445, 3804.543454785388, 3809.6190380961684, 3841.5582208045876, 4349.456317960255, 4361.6510679145595, 4383.956160438354, 4390.626796722315, 4395.384646149541, 5072.188449848007, 5072.51811077947, 5073.695578265316, 5079.771410286546, 5117.385913690381, 4278.7004909656025, 4313.827446902152, 4323.7621930793475, 4326.178026175016, 4410.669199809963, 4578.65660075489, 4647.279671650899, 4658.417697787777, 4667.579917807414, 4668.5564871337165, 4830.130576496194, 4835.975820120877, 4840.733759643183, 4841.201201801663, 4896.583790377857, 5065.240213967787, 5083.771230294719, 5084.339035925306, 5088.0587091388215, 5126.307948426915, 4708.882277943078, 4754.762261886823, 4766.737829419415, 4784.354112194809, 4786.8085276588545, 4965.1311020571975, 4992.326035985096, 4997.0, 4997.200447928314, 5023.924641130313, 5049.671771334852, 5052.418971818141, 5053.140966035856, 5065.240213967934, 5085.135526960499, 4800.855974320776, 4848.369711937426, 4881.389826271716, 4882.36529251195, 4960.206366981266, 4895.923051264341, 4901.803927842846, 4909.0672772173175, 4912.287718280758, 4941.50584941503, 4838.588719958702, 4875.634327425528, 4888.169011268022, 4894.779734912009, 4897.730624815526, 4826.686265392738, 4907.092187945242, 4958.851234462972, 4986.252062190701, 5002.124628189979, 4876.561109500162, 4888.706677599355, 4894.2658601210105, 4928.753562321941, 4945.7527123644395], "value": 4660.3504658063375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.1457776890243, 581.4069648958063, 581.9359870414254, 585.7393459910336, 588.402771187245, 27117.186484405476, 39856.60143398565, 40948.59051409485, 40968.79515602425, 44281.77859110708, 40288.798556007256, 40472.0, 40553.391699124535, 40596.797016014876, 40642.0, 7721.189275126101, 7900.209979002111, 7900.288973992339, 7903.920960790405, 7904.920950790504, 7514.361279291239, 9888.851667224997, 10124.493775311243, 10125.645602403918, 10417.843732344021, 10253.0, 10344.689659310232, 10347.948260258689, 10348.068673819354, 10348.223883208751, 10131.898681013206, 10176.185905127571, 10181.847272290921, 10198.949005254963, 10205.744856378577, 9967.700968970943, 10040.397576145455, 10044.096031357212, 10054.698359049167, 10063.647772327933, 38712.0, 38913.805430972665, 39052.609473905184, 39061.41407878884, 39082.60917390818, 2996.955045674317, 3107.0, 3203.7116659500525, 3251.073444068429, 3263.96736032639, 29310.41379172426, 29329.853350733323, 29691.257718556993, 29692.257693557618, 29721.40557188844, 3370.5449764281752, 3373.1567108222944, 3487.3548393547276, 3599.406097993854, 3604.3872541667715, 251926.0, 253327.0, 254503.99999999814, 255474.0, 256942.0, 31451.0, 53060.0, 53208.467915321125, 53265.0, 53335.19997200045, 31947.840260799014, 33684.0, 33763.32473350568, 33870.661293387246, 34091.4886276706, 13441.39513721885, 13577.456901723821, 13596.524121655892, 13599.252041137832, 13621.863781362257, 9580.95209523948, 10969.780604387868, 11341.26281791681, 11399.0, 11411.657650270505, 7731.420143489261, 7844.0, 7941.722039728697, 7957.363410927228, 7980.281774640311, 3054.5418187271644, 3104.2549788051156, 3133.5143052826847, 3140.0736782649174, 3155.952660710091, 814.7922279818608, 895.230102112196, 942.707760594218, 982.1259079419323, 1012.9290949633455, 27933.816110578173, 28463.57304640432, 28499.28751781222, 28852.711472885003, 29142.41715165685, 947.0907928388824, 955.2262667239559, 961.1541843177959, 1028.9639862604922, 1070.737669271018, 14532.491362802251, 14936.0, 15271.694566108616, 15454.922725386301, 15622.140782257085, 404063.0, 404990.0, 408280.0, 408493.0, 408628.0, 49075.75462122667, 49516.77174527155, 49654.503454964986, 49728.50271497311, 49753.25370119451, 1064.11678307005, 1106.0985296982933, 1165.341582006164, 1193.3675152169365, 1208.8368070310396, 25156.742162891416, 28108.43783124367, 28831.279218019718, 29303.560446592892, 29380.559291610214, 41948.37077443902, 42652.57347426486, 42687.786561067, 42714.78642606767, 42760.358594620484, 306214.0, 306224.0, 307917.0, 308167.0, 308236.0, 25311.87344063268, 26261.737382625928, 26467.867660661574, 27249.455010900067, 27278.181654550393, 39819.20361592694, 42201.0, 42493.787531062146, 43346.0, 43880.0, 35861.64138358687, 36694.26611467809, 37082.8145859269, 39802.80098599489, 41337.379939301536, 36320.45519317265, 36598.45102323414, 36703.265934680625, 36719.0, 36848.63151368559, 36186.0, 36694.8165259172, 36816.81591592025, 37608.247835043694, 37679.43480847844, 31195.84402077975, 32785.0, 33322.83338583291, 33671.663283366855, 33671.663283366855, 25011.499770004863, 25615.359616009002, 25793.142623661308, 25816.8709156453, 25861.870690646425], "value": 17080.193359239413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.2918194745903, 359.70504186567024, 360.28843035005866, 363.61093629816094, 382.6020938224247, 12817.423215955281, 16121.838781612196, 16427.917860410696, 17788.199531021102, 17979.370722024698, 16778.244978975945, 17248.447639712424, 17338.0, 17370.47888563342, 17458.0, 4891.217405215166, 4930.506949305077, 5087.720175390352, 5102.132637451624, 5104.617153713468, 3681.8159092045425, 5084.0594490019275, 5173.198154286091, 5647.689377084259, 5939.910901336483, 5903.173555702205, 5912.083627037795, 5915.5859089863725, 5918.378570250126, 5920.0, 5157.0, 5415.620906536544, 5434.184872269152, 5504.2018907258225, 5899.557533185007, 5315.681059136426, 5341.0920143575295, 5347.759350829222, 5355.437679043722, 5356.91964620531, 11947.163698541106, 12215.75568488634, 12229.143959922809, 12354.75290494194, 12976.935115324362, 1777.2091419318408, 1801.135454981603, 1858.1545396844404, 2169.07011437096, 2393.988030059856, 8776.561171941377, 8823.161799629024, 8870.46777193364, 9136.0, 9317.254619630414, 1768.6727955328176, 1779.7241427578747, 1780.4747599458192, 1780.6616742818953, 1788.3561917709615, 46525.767371162925, 61708.38291617071, 63785.36214637887, 66081.0, 66708.6664566679, 9629.32594718363, 9787.85318220213, 10088.394696318244, 10185.74535636615, 10221.591136354611, 13968.441262349595, 14020.789688154686, 14039.227842468557, 14054.297285135703, 14055.718885622231, 7349.669264883093, 7526.24737526243, 7527.134379546423, 7609.478104379146, 7638.656260468292, 6413.903791443132, 6483.092367068614, 6552.541322107502, 6758.222804377461, 6767.695453704595, 4289.6139347458875, 4295.098029413858, 4359.607635312837, 4361.672874534423, 4449.17690227309, 1740.782402199725, 1772.1936518883876, 1818.0547513028978, 1820.4447643468868, 1830.6704793137096, 774.295391194022, 861.6897916749965, 869.0527325215545, 900.6532484993324, 903.8192361527721, 16775.748363774554, 16780.412685555948, 17182.74225886613, 17268.222929968182, 17821.01984390871, 862.1938487514194, 871.263782104121, 878.3719640457103, 911.3984770051734, 940.741296143566, 9361.251099912128, 9753.463559504153, 9982.450965196984, 9988.25088118394, 10150.695479135638, 288760.0, 289598.0, 293132.0, 419147.0, 420065.0, 76183.61908190534, 79494.0, 80113.19886801174, 80626.5968670153, 82411.0, 928.5821380378857, 929.3958926697701, 963.6964356227905, 980.2011360740973, 984.104464936904, 15580.298886549906, 16119.838801611833, 16453.341866325212, 16490.257938392562, 16754.664906701553, 37160.44259336058, 37562.12189390465, 37748.62251377451, 38049.80975095107, 39029.0, 49203.0, 53775.73112134414, 55043.0, 55952.0, 56924.43075569357, 7302.123745150616, 7580.507267027846, 7686.730964416219, 7705.30652241301, 7722.111957125003, 10641.574337026432, 11644.6506604802, 11802.055835532965, 11870.347130907883, 11973.246153384944, 9818.852717209105, 10261.435621040915, 10281.280310378203, 10316.587336506456, 10358.792824143324, 9390.060993900555, 9597.760055998657, 9657.179139773198, 9666.806663866824, 9870.555824987749, 10085.89914100879, 10160.796784064425, 10284.845727313947, 10316.638917637847, 10729.248952573245, 7364.711175544366, 7607.885881711668, 7654.8086297843, 7771.37828973692, 7811.336036436966, 9998.550065246936, 10013.349132306377, 10207.84688229692, 10843.891561084289, 11011.449427528752], "value": 8086.395931415555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1240.2372540887357, 1338.9129706569072, 1387.3618135657598, 1423.459085547492, 1681.3442757324638, 6607.570507916988, 7440.144383395911, 7665.8083547911265, 7699.191584883577, 7954.323882469997, 7764.611769411535, 7804.921950780505, 7819.100803407596, 7833.255840695125, 7834.412419068564, 2192.079326682793, 2224.877631730254, 2227.9331620051425, 2228.3760547046772, 2230.3420490955127, 2154.1706443019393, 2185.016742465889, 2186.6392045312505, 2187.2344679362254, 2205.106931692659, 2156.083664442606, 2169.175713228971, 2170.012644246864, 2172.706684597583, 2193.473566344082, 2107.778683238261, 2108.5888251790907, 2111.5671287386126, 2113.3871177358546, 2113.50332671822, 2071.0, 2146.6887301341376, 2147.795959383856, 2150.6558950567833, 2151.5696860627936, 7752.612369381508, 7797.259260370256, 7809.3752499799875, 7814.960925195394, 7816.296533312029, 4551.180787458256, 4551.749653769041, 4692.061587682477, 4697.4832768395445, 4700.365450664185, 2806.943861122766, 2873.439679262545, 2876.6116574262414, 2893.3779237464046, 2893.4936386132345, 853.3685073045964, 892.3619611977402, 898.9685361012398, 901.535709109809, 926.3414001814834, 10987.230893837435, 11185.552577896875, 11187.384693841836, 11193.10455163585, 11201.215914885961, 3970.4441378207075, 4209.095044565433, 4209.1792100540715, 4300.8709738707885, 4302.741835489881, 8718.0, 8718.65125394977, 8722.825543489094, 8733.03936566977, 8734.301255899512, 4701.200795864711, 4718.646101542398, 4724.503927087675, 4724.858254252378, 4725.054989002213, 3709.4806727058235, 4163.75017498951, 4353.412289340962, 4392.231359512072, 4398.604125628644, 3458.4639380896, 3503.964960350364, 3626.256617393428, 3628.346897558412, 3631.727620428426, 1024.2113572549094, 1046.0637729232446, 1050.5010120192846, 1074.2856000759564, 1077.7144056824948, 692.3526502719973, 830.2859540794911, 833.7040350675544, 842.4313588327855, 842.5787106446777, 5131.512506311894, 5145.151050076695, 5195.804964858104, 5300.681959082467, 5390.21841832932, 369.51039872169486, 385.00284263757004, 389.5364516225682, 392.1275162762887, 394.5994815262511, 3517.542809244418, 3523.224890524081, 3541.1678255609913, 3578.443591405313, 3589.8743543976357, 9772.560234789451, 10034.347767395097, 10126.240531960133, 10142.543585538668, 10184.592616295266, 9268.814623707489, 9673.516324183762, 10065.949670251748, 10078.294519383573, 10088.243381746399, 7335.88996165058, 7490.400767938552, 7512.6243687815395, 7529.623518824146, 7535.434842386843, 5152.175651895679, 5206.297149884717, 5366.141417373202, 5442.755076021509, 5529.585281103853, 8760.824783504422, 8892.466452012899, 9019.368644194843, 9059.184673379428, 9067.365284430027, 9228.492432915986, 9241.58412871409, 9337.673181438617, 9352.3453358267, 9354.485503297385, 6203.875922481616, 6793.184817821893, 7026.8945965809535, 7136.179339376038, 7146.499235160674, 7531.3598344141365, 7838.80402989907, 7851.489653172525, 7852.13626501073, 7856.489328193649, 6683.532152749261, 6792.117024786746, 6848.691808868513, 6881.13985751781, 6979.930200698132, 6695.196576410862, 6747.359000894907, 6833.624150671562, 6869.828254293682, 6987.0, 6459.935400645933, 6511.120998665119, 6547.70535325902, 6554.508411869034, 6555.0, 4526.547345265447, 4540.818367265405, 4571.131485017836, 4641.0, 4645.349651048923, 4517.073999830092, 4524.095180963886, 4556.316552517016, 4568.063546972929, 4772.594329482033], "value": 4048.3244796795952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.75002497752024, 282.2984882566821, 286.4071372259423, 293.72389953443775, 294.1015198568374, 4817.675139336679, 4829.903401931968, 4833.250846118853, 4849.466558678542, 4856.125897338477, 4506.324051392293, 4616.33063205835, 4648.163330600491, 4747.216709242979, 4793.664443488957, 3760.458212133026, 3787.867424640124, 3790.5830358660523, 3799.9100494727927, 3802.258559580869, 3775.603561626031, 3789.7157713171487, 3809.4095415210686, 3813.523309586302, 3820.2741479118877, 3575.6245594212623, 3594.7663401878926, 3704.8885334399574, 3706.518152640153, 3744.9438258426144, 3702.426123950792, 3713.535808023997, 3724.9813750931344, 3728.8694895678655, 3743.232637309345, 3290.44062509374, 3291.753118516121, 3295.1103202135528, 3299.3566254580364, 3302.091924720697, 4509.797059132314, 4719.787609557578, 4731.384919960383, 4741.454732705714, 4746.359241502422, 4245.299525578307, 4245.363195520666, 4247.936280955788, 4250.744955302692, 4278.44380230568, 5031.52200540948, 5042.873928151789, 5044.319016932704, 5054.974725126351, 5081.923771143436, 3528.752987290891, 3533.823308834548, 3544.9113772155642, 3561.7355838677154, 3569.6965757910607, 5003.3495645565845, 5223.537409525339, 5235.554977826928, 5256.316678831766, 5311.9468805311835, 3551.147724546142, 3563.465480177942, 3569.1434055826417, 3571.928561428757, 3579.8365531201944, 3639.872604458831, 3640.6541378568995, 3647.8176091195332, 3648.1791596891017, 3650.9817450912574, 4014.6186112319283, 4196.391523229176, 4199.853005144866, 4211.578842115764, 4222.809973551136, 5013.523715247045, 5020.171671674206, 5020.949790502122, 5026.723530205801, 5031.698098114051, 1256.47856139701, 1273.0833799664226, 1277.916935399196, 1278.4758249117817, 1281.6667666406615, 810.2464707821782, 823.3039937728299, 835.306695442781, 842.1115722912264, 844.3287586368851, 4435.512093669694, 4442.311441726538, 4442.3336499524685, 4446.155230506193, 4448.488423831301, 5131.230315452712, 5165.483451654804, 5174.016936781999, 5177.896442071213, 5194.714290714047, 4372.759498227628, 4407.294832826732, 4444.155610434007, 4459.44256967879, 4482.529334419904, 2726.291164297297, 2741.7669498092487, 2743.2181828178873, 2745.4509098180442, 2757.214193954713, 4905.754712264444, 4908.410990681141, 4909.5090490950615, 4913.631477639191, 4928.531789479994, 5098.668586541937, 5289.233061206103, 5290.761915713802, 5384.115559554125, 5384.40771515133, 4955.776990035457, 4993.950060499421, 5015.27278544609, 5023.246513023076, 5036.395632524121, 5216.0, 5287.365516138088, 5299.33758280215, 5300.867478313073, 5321.574274057989, 4896.090524695301, 5094.694318340911, 5097.158968770112, 5110.419238284642, 5184.559312458483, 4954.1825598775795, 4964.131277026579, 4993.725345106054, 4995.425526064549, 4996.325496058131, 3655.5430571178604, 3668.046307960003, 3672.4674922135605, 3682.8526858926416, 3684.594694583647, 5160.225966105114, 5214.921776173284, 5250.763715632882, 5269.473052694853, 5310.3448275862065, 5032.773525191448, 5049.8737531562, 5051.87370315745, 5066.82266120684, 5094.337736094284, 5101.311322971352, 5131.204663277198, 5166.405863325615, 5170.1986192140275, 5173.611979101507, 4903.95096049035, 4983.377077865267, 4989.650724449254, 5004.899902002013, 5029.0, 4850.490698476609, 4925.45819959797, 5074.31496747949, 5075.2387141929, 5078.5175408336145, 4952.702837829742, 4953.405591329064, 4955.1576232039915, 4966.205407134841, 4970.229614409773], "value": 3790.068231146094, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.9318075011748, 701.1270967645282, 702.0101656664104, 737.542723511423, 739.0650826704217, 12604.495820167194, 13287.0, 13292.069555131146, 13307.600771976857, 13344.466221351146, 12692.30192339421, 12803.23180609164, 12823.42294596743, 12826.935865320684, 12847.100702950775, 617.3425302053321, 737.6016950846537, 743.1082700759097, 760.8364201696634, 762.0854974031157, 755.04863871522, 757.2919320435383, 757.3486801350834, 757.7916073079892, 757.8257000889798, 812.3947658994028, 814.3444527155667, 814.6659869453525, 815.9918400816005, 816.3713940265992, 649.0653459018997, 781.1134362498548, 800.1478425476906, 806.15353878428, 806.395203597302, 587.5417174603816, 588.4204059001879, 588.6203398807788, 590.1236663554618, 590.2739630254767, 12231.204971676812, 12245.32650704211, 12251.958583520322, 12257.509699612005, 12271.141020128594, 12303.75392492154, 12486.375681215903, 12496.0, 12729.809052864215, 13091.149075310075, 10936.398498082603, 11112.388818614972, 11490.36802975836, 11633.0, 11819.527218911233, 3734.066483379155, 3815.866444674451, 3819.102510909934, 3904.6095390460728, 3904.726669133162, 52179.73910130425, 54531.0, 54595.0, 60644.39355606476, 61580.7683846325, 10118.59525618967, 10551.630692925712, 10558.841617375745, 10567.577296908192, 10597.62908298206, 18599.44201673925, 18606.0, 18610.53473663142, 18616.06919654012, 18625.254989800527, 6735.932640673628, 6757.054012438164, 6761.898571521431, 6828.7609933653075, 7062.6821793019435, 3677.889663310105, 3736.925261494755, 3782.319182547113, 3819.987703258583, 3847.0, 2725.11433784021, 2826.5053615617185, 2826.689064202977, 2828.660560732725, 2830.1226619747945, 467.525461656419, 527.614841165951, 540.5702466539111, 541.3666010767333, 562.2157090858311, 487.98048078077187, 565.2595100418465, 577.532198918877, 582.8338923406893, 604.0425924908981, 7821.5698136601895, 8801.47191168532, 8858.778530536789, 8966.596503157374, 9011.954940225258, 454.14167223946765, 481.55456203012074, 508.936382952131, 547.9561635069185, 567.9943200568024, 3908.5700572936953, 4144.958550414518, 4176.979115104405, 4255.212785634668, 4259.403683484315, 91983.0, 95218.52390738002, 96936.51531742296, 97327.0, 99266.0, 87415.56292218498, 87576.56211719027, 88615.0, 91949.18431933227, 92927.0, 75673.0, 76388.0, 76749.6162519195, 76886.0, 77106.0, 7663.88504172449, 7735.071791385069, 7843.56860372685, 7976.481528700616, 8094.473859199134, 43781.34327985146, 44517.0, 45005.0, 45678.77160614176, 46210.537894620626, 57839.710801445726, 65620.67189664021, 65639.690316938, 68279.65860170667, 68871.65564172147, 8557.657693692432, 8818.206361427503, 9397.0, 9456.290778191526, 9634.71095867125, 10042.598296068367, 10154.898451015692, 10273.589056437659, 10536.683899482721, 10817.0, 9506.334556580972, 11488.425578721197, 12049.277043377426, 12088.214266072677, 12178.0, 12214.57248996281, 12255.387230638611, 12296.815547766611, 12432.564860229848, 12445.25328480294, 11627.767444651228, 11812.76374472523, 12020.519179232733, 12263.632091037283, 12545.435455404346, 10163.695089147337, 10380.377177369382, 10569.207309451667, 10803.405812680377, 10887.346759194474, 10266.384016959006, 10350.896491035295, 10448.373097614167, 10458.529366178387, 10842.512086955949], "value": 6511.262775497511, "unit": "score"}], "name": "dromaeo_css", "value": 6329.7106060217475, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 112.0, 113.0, 114.0, 113.0, 112.0, 114.0, 113.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 123.0, 188.0, 121.0, 122.0, 144.0, 120.0, 154.0, 127.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 164.0, 161.0, 162.0, 160.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 122.0, 76.0, 76.0, 75.0, 74.0, 106.0, 75.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 120.0, 86.0, 85.0, 85.0, 126.0, 89.0, 85.0, 120.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 61.0, 73.0, 62.0, 63.0, 62.0, 65.0, 61.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 78.0, 90.0, 77.0, 78.0, 79.0, 79.0, 78.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 130.0, 129.0, 130.0, 128.0, 139.0, 128.0, 129.0, 226.0, 127.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 165.0, 166.0, 167.0, 170.0, 165.0, 171.0, 172.0, 168.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 66.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1533.9999999999998, "alertThreshold": 2.0}]} 09:42:21 INFO - Return code: 0 09:42:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:42:21 INFO - # TBPL SUCCESS # 09:42:21 INFO - Running post-action listener: _resource_record_post_action 09:42:21 INFO - [mozharness: 2016-07-26 16:42:21.777543Z] Finished run-tests step (success) 09:42:21 INFO - Running post-run listener: _resource_record_post_run 09:42:21 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536064000; Read time: 12; Write time: 467236 09:42:21 INFO - TinderboxPrint: CPU usage
13.0% 09:42:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:42:21 INFO - TinderboxPrint: I/O write bytes / time
536,064,000 / 467,236 09:42:21 INFO - TinderboxPrint: CPU idle
8,335.8 (87.0%) 09:42:21 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 09:42:21 INFO - TinderboxPrint: Swap in / out
0 / 0 09:42:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246820864; Read time: 0; Write time: 257540 09:42:21 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288628736; Read time: 0; Write time: 209696 09:42:22 INFO - Running post-run listener: _upload_blobber_files 09:42:22 INFO - Blob upload gear active. 09:42:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:42:22 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 09:42:22 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'] 09:42:22 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 09:42:22 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:42:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:42:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:23 INFO - (blobuploader) - INFO - Done attempting. 09:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:42:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:24 INFO - (blobuploader) - INFO - Done attempting. 09:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:42:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:31 INFO - (blobuploader) - INFO - Done attempting. 09:42:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:31 INFO - Return code: 0 09:42:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:42:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91e8445025c1b3402f2058424f046317d853c1893b94a8fc2c5865cbdbfbfc483380c3ffaa3b1ebfd2d3aabe9640bdd3d23ae0142ff231561dc9a7aabbfbb2ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/99bcdd35a464bb8d45f18cb50e5ef97a473d4868b5b0cb80cb45801770c0bd2f22cef0c3a31c99560817ec5e3c80c9cb125d0acc00741af60e5792c4ae08230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50772390d9ac7251dfa045bd488d15a459eccfaf7e0c5d46524b243d1749a397f36d0ba62766ac2baf05e5e375638568710f9e979a9238577daed1616f74b6e8"} 09:42:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:42:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:42:31 INFO - Contents: 09:42:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91e8445025c1b3402f2058424f046317d853c1893b94a8fc2c5865cbdbfbfc483380c3ffaa3b1ebfd2d3aabe9640bdd3d23ae0142ff231561dc9a7aabbfbb2ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/99bcdd35a464bb8d45f18cb50e5ef97a473d4868b5b0cb80cb45801770c0bd2f22cef0c3a31c99560817ec5e3c80c9cb125d0acc00741af60e5792c4ae08230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50772390d9ac7251dfa045bd488d15a459eccfaf7e0c5d46524b243d1749a397f36d0ba62766ac2baf05e5e375638568710f9e979a9238577daed1616f74b6e8"} 09:42:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:31 INFO - Copying logs to upload dir... 09:42:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:42:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.600748 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-26 09:42:31.162752) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 09:42:31.168881) ========= 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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 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/91e8445025c1b3402f2058424f046317d853c1893b94a8fc2c5865cbdbfbfc483380c3ffaa3b1ebfd2d3aabe9640bdd3d23ae0142ff231561dc9a7aabbfbb2ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/99bcdd35a464bb8d45f18cb50e5ef97a473d4868b5b0cb80cb45801770c0bd2f22cef0c3a31c99560817ec5e3c80c9cb125d0acc00741af60e5792c4ae08230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50772390d9ac7251dfa045bd488d15a459eccfaf7e0c5d46524b243d1749a397f36d0ba62766ac2baf05e5e375638568710f9e979a9238577daed1616f74b6e8"} build_url:https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/cOG10XlsRze5SpQDuytnig/artifacts/public/build/firefox-50.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/91e8445025c1b3402f2058424f046317d853c1893b94a8fc2c5865cbdbfbfc483380c3ffaa3b1ebfd2d3aabe9640bdd3d23ae0142ff231561dc9a7aabbfbb2ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/99bcdd35a464bb8d45f18cb50e5ef97a473d4868b5b0cb80cb45801770c0bd2f22cef0c3a31c99560817ec5e3c80c9cb125d0acc00741af60e5792c4ae08230d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50772390d9ac7251dfa045bd488d15a459eccfaf7e0c5d46524b243d1749a397f36d0ba62766ac2baf05e5e375638568710f9e979a9238577daed1616f74b6e8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 09:42:31.194155) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:42:31.194527) ========= 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=62c568580acbfdaac3eec7be000001dc-1469550104.444993-2028985631 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 09:42:31.280210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 09:42:31.280533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 09:42:31.280831) ========= ========= Total master_lag: 0.19 =========