builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-024 starttime: 1487686015.53 results: success (0) revision: b69d4316561aa125612509a202922455d2de03e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.535114) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.535559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.535865) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.549598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.549998) ========= 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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-21 06:06:55-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2017-02-21 06:06:55 (218 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127938 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.689091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.689412) ========= 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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022103 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:55.738676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 06:06:55.739007) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b69d4316561aa125612509a202922455d2de03e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev b69d4316561aa125612509a202922455d2de03e5 --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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-21 06:06:55,839 truncating revision to first 12 chars 2017-02-21 06:06:55,839 Setting DEBUG logging. 2017-02-21 06:06:55,839 attempt 1/10 2017-02-21 06:06:55,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b69d4316561a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 06:06:56,750 unpacking tar archive at: mozilla-central-b69d4316561a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.212222 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-21 06:06:56.963853) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:56.964161) ========= script_repo_revision: b69d4316561aa125612509a202922455d2de03e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:56.964560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:56.964873) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 06:06:56.972741) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-21 06:06:56.973029) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:06:57 INFO - MultiFileLogger online at 20170221 06:06:57 in /builds/slave/test 06:06:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 06:06:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:06:57 INFO - {'append_to_log': False, 06:06:57 INFO - 'base_work_dir': '/builds/slave/test', 06:06:57 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:06:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:06:57 INFO - 'branch': 'Firefox-Non-PGO', 06:06:57 INFO - 'buildbot_json_path': 'buildprops.json', 06:06:57 INFO - 'config_files': ('talos/linux_config.py',), 06:06:57 INFO - 'default_actions': ('clobber', 06:06:57 INFO - 'read-buildbot-config', 06:06:57 INFO - 'download-and-extract', 06:06:57 INFO - 'populate-webroot', 06:06:57 INFO - 'create-virtualenv', 06:06:57 INFO - 'install', 06:06:57 INFO - 'run-tests'), 06:06:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:06:57 INFO - 'download_minidump_stackwalk': True, 06:06:57 INFO - 'download_symbols': 'ondemand', 06:06:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:06:57 INFO - 'tooltool.py': '/tools/tooltool.py', 06:06:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:06:57 INFO - '/tools/misc-python/virtualenv.py')}, 06:06:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:06:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:06:57 INFO - 'gecko_profile': False, 06:06:57 INFO - 'gecko_profile_interval': 0, 06:06:57 INFO - 'installer_path': 'installer.exe', 06:06:57 INFO - 'log_level': 'info', 06:06:57 INFO - 'log_name': 'talos', 06:06:57 INFO - 'log_to_console': True, 06:06:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:06:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:06:57 INFO - 'opt_config_files': (), 06:06:57 INFO - 'pip_index': False, 06:06:57 INFO - 'suite': 'dromaeojs-e10s', 06:06:57 INFO - 'system_bits': '32', 06:06:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:06:57 INFO - 'title': 'talos-linux64-ix-024', 06:06:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:06:57 INFO - 'use_talos_json': True, 06:06:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:06:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:06:57 INFO - 'work_dir': 'build'} 06:06:57 INFO - [mozharness: 2017-02-21 14:06:57.166724Z] Running clobber step. 06:06:57 INFO - Running pre-action listener: _resource_record_pre_action 06:06:57 INFO - Running main action method: clobber 06:06:57 INFO - rmtree: /builds/slave/test/build 06:06:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:06:57 INFO - Running post-action listener: _resource_record_post_action 06:06:57 INFO - [mozharness: 2017-02-21 14:06:57.644600Z] Finished clobber step (success) 06:06:57 INFO - [mozharness: 2017-02-21 14:06:57.644700Z] Running read-buildbot-config step. 06:06:57 INFO - Running pre-action listener: _resource_record_pre_action 06:06:57 INFO - Running main action method: read_buildbot_config 06:06:57 INFO - Using buildbot properties: 06:06:57 INFO - { 06:06:57 INFO - "project": "", 06:06:57 INFO - "product": "firefox", 06:06:57 INFO - "who": "cbook@mozilla.com", 06:06:57 INFO - "installer_path": "public/build/target.tar.bz2", 06:06:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 06:06:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:06:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 06:06:57 INFO - "stage_platform": "linux64", 06:06:57 INFO - "basedir": "/builds/slave/test", 06:06:57 INFO - "buildnumber": 428, 06:06:57 INFO - "platform": "ubuntu64_hw", 06:06:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:06:57 INFO - "slavebuilddir": "test", 06:06:57 INFO - "taskId": "YcdxvMApQWmyyW_fKm418A", 06:06:57 INFO - "branch": "mozilla-central", 06:06:57 INFO - "script_repo_revision": "production", 06:06:57 INFO - "revision": "b69d4316561aa125612509a202922455d2de03e5", 06:06:57 INFO - "slavename": "talos-linux64-ix-024", 06:06:57 INFO - "repo_path": "mozilla-central" 06:06:57 INFO - } 06:06:57 INFO - Finding installer, test and symbols from parent task. 06:06:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YcdxvMApQWmyyW_fKm418A'}, attempt #1 06:06:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YcdxvMApQWmyyW_fKm418A'}, attempt #1 06:06:58 INFO - Task dependencies: YxPaR-_0Qx6OLVtFVJE4ZA 06:06:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA'}, attempt #1 06:06:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 06:06:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/test_packages.json 06:06:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.crashreporter-symbols.zip 06:06:59 INFO - Running post-action listener: _resource_record_post_action 06:06:59 INFO - [mozharness: 2017-02-21 14:06:59.104301Z] Finished read-buildbot-config step (success) 06:06:59 INFO - [mozharness: 2017-02-21 14:06:59.104517Z] Running download-and-extract step. 06:06:59 INFO - Running pre-action listener: _resource_record_pre_action 06:06:59 INFO - Running main action method: download_and_extract 06:06:59 INFO - mkdir: /builds/slave/test/build/tests 06:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:59 INFO - trying https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.test_packages.json 06:06:59 INFO - Downloading https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:06:59 INFO - Downloaded 896 bytes. 06:06:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:06:59 INFO - Using the following test package requirements: 06:06:59 INFO - {u'common': [u'target.common.tests.zip'], 06:06:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:06:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:06:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:06:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:06:59 INFO - u'mozbase': [u'target.common.tests.zip'], 06:06:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:06:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:06:59 INFO - u'web-platform': [u'target.common.tests.zip', 06:06:59 INFO - u'target.web-platform.tests.zip'], 06:06:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:06:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:06:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip 06:06:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:06:59 INFO - Fetch https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip into memory 06:07:00 INFO - Expected file size: 57165804 06:07:00 INFO - Obtained file size: 57165804 06:07:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:07:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip 06:07:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:07:10 INFO - Fetch https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.common.tests.zip into memory 06:07:11 INFO - Expected file size: 57165804 06:07:11 INFO - Obtained file size: 57165804 06:07:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.talos.tests.zip 06:07:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:07:22 INFO - Fetch https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.talos.tests.zip into memory 06:07:23 INFO - Expected file size: 14404319 06:07:23 INFO - Obtained file size: 14404319 06:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:23 INFO - trying https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 06:07:23 INFO - Downloading https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:07:25 INFO - Downloaded 61506987 bytes. 06:07:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 06:07:25 INFO - mkdir: /builds/slave/test/properties 06:07:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:25 INFO - Contents: 06:07:25 INFO - build_url:https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 06:07:25 INFO - Running post-action listener: _resource_record_post_action 06:07:25 INFO - Running post-action listener: set_extra_try_arguments 06:07:25 INFO - [mozharness: 2017-02-21 14:07:25.225285Z] Finished download-and-extract step (success) 06:07:25 INFO - [mozharness: 2017-02-21 14:07:25.225381Z] Running populate-webroot step. 06:07:25 INFO - Running pre-action listener: _resource_record_pre_action 06:07:25 INFO - Running main action method: populate_webroot 06:07:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:25 INFO - '--tppagecycles', 06:07:25 INFO - '7'], 06:07:25 INFO - 'tests': ['tsvgm']}}, 06:07:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:07:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['damp', 'tps']}, 06:07:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'tests': ['damp', 'tps']}, 06:07:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['dromaeo_dom']}, 06:07:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['basic_compositor_video']}, 06:07:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:07:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['a11yr', 06:07:25 INFO - 'ts_paint', 06:07:25 INFO - 'tpaint', 06:07:25 INFO - 'sessionrestore', 06:07:25 INFO - 'sessionrestore_no_auto_restore', 06:07:25 INFO - 'tabpaint']}, 06:07:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['tsvgx', 06:07:25 INFO - 'tsvgr_opacity', 06:07:25 INFO - 'tart', 06:07:25 INFO - 'tscrollx', 06:07:25 INFO - 'cart', 06:07:25 INFO - 'tsvg_static']}, 06:07:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:25 INFO - 'tsvgr_opacity', 06:07:25 INFO - 'tart', 06:07:25 INFO - 'tscrollx', 06:07:25 INFO - 'cart', 06:07:25 INFO - 'tsvg_static']}, 06:07:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'talos_options': ['--disable-e10s'], 06:07:25 INFO - 'tests': ['tp5o']}, 06:07:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:07:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'talos_options': ['--disable-e10s', 06:07:25 INFO - '--xperf_path', 06:07:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:25 INFO - 'tests': ['tp5n']}, 06:07:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:07:25 INFO - 'talos_options': ['--xperf_path', 06:07:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:25 INFO - 'tests': ['tp5n']}}, 06:07:25 INFO - 'talos.zip': {'path': '', 06:07:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:25 INFO - Running post-action listener: _resource_record_post_action 06:07:25 INFO - [mozharness: 2017-02-21 14:07:25.238413Z] Finished populate-webroot step (success) 06:07:25 INFO - [mozharness: 2017-02-21 14:07:25.238521Z] Running create-virtualenv step. 06:07:25 INFO - Running pre-action listener: _resource_record_pre_action 06:07:25 INFO - Running main action method: create_virtualenv 06:07:25 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:25 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 06:07:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:25 INFO - Using real prefix '/usr' 06:07:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 06:07:27 INFO - Installing pip.................done. 06:07:27 INFO - Return code: 0 06:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:07:28 INFO - Reading from file tmpfile_stdout 06:07:28 INFO - Output received: 06:07:28 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:07:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:28 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 06:07:28 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 06:07:28 INFO - Using env: {'DISPLAY': ':0', 06:07:28 INFO - 'HOME': '/home/cltbld', 06:07:28 INFO - 'LANG': 'en_US.UTF-8', 06:07:28 INFO - 'LANGUAGE': 'en_US:en', 06:07:28 INFO - 'LOGNAME': 'cltbld', 06:07:28 INFO - 'MAIL': '/var/mail/cltbld', 06:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:28 INFO - 'MOZ_NO_REMOTE': '1', 06:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:28 INFO - 'NO_EM_RESTART': '1', 06:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:28 INFO - 'PWD': '/builds/slave/test', 06:07:28 INFO - 'SHELL': '/bin/bash', 06:07:28 INFO - 'SHLVL': '1', 06:07:28 INFO - 'TERM': 'linux', 06:07:28 INFO - 'TMOUT': '86400', 06:07:28 INFO - 'USER': 'cltbld', 06:07:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:28 INFO - '_': '/tools/buildbot/bin/python'} 06:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:28 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 06:07:28 INFO - Cleaning up... 06:07:28 INFO - Return code: 0 06:07:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:28 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 06:07:28 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 06:07:28 INFO - Using env: {'DISPLAY': ':0', 06:07:28 INFO - 'HOME': '/home/cltbld', 06:07:28 INFO - 'LANG': 'en_US.UTF-8', 06:07:28 INFO - 'LANGUAGE': 'en_US:en', 06:07:28 INFO - 'LOGNAME': 'cltbld', 06:07:28 INFO - 'MAIL': '/var/mail/cltbld', 06:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:28 INFO - 'MOZ_NO_REMOTE': '1', 06:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:28 INFO - 'NO_EM_RESTART': '1', 06:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:28 INFO - 'PWD': '/builds/slave/test', 06:07:28 INFO - 'SHELL': '/bin/bash', 06:07:28 INFO - 'SHLVL': '1', 06:07:28 INFO - 'TERM': 'linux', 06:07:28 INFO - 'TMOUT': '86400', 06:07:28 INFO - 'USER': 'cltbld', 06:07:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:28 INFO - '_': '/tools/buildbot/bin/python'} 06:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:28 INFO - Downloading/unpacking psutil>=3.1.1 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:28 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 06:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:07:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:30 INFO - Installing collected packages: psutil 06:07:30 INFO - Running setup.py install for psutil 06:07:30 INFO - building 'psutil._psutil_linux' extension 06:07:30 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 06:07:30 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 06:07:30 INFO - building 'psutil._psutil_posix' extension 06:07:30 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 06:07:30 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 06:07:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:31 INFO - Successfully installed psutil 06:07:31 INFO - Cleaning up... 06:07:31 INFO - Return code: 0 06:07:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:07:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:31 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:31 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 06:07:31 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 06:07:31 INFO - Using env: {'DISPLAY': ':0', 06:07:31 INFO - 'HOME': '/home/cltbld', 06:07:31 INFO - 'LANG': 'en_US.UTF-8', 06:07:31 INFO - 'LANGUAGE': 'en_US:en', 06:07:31 INFO - 'LOGNAME': 'cltbld', 06:07:31 INFO - 'MAIL': '/var/mail/cltbld', 06:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:31 INFO - 'MOZ_NO_REMOTE': '1', 06:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:31 INFO - 'NO_EM_RESTART': '1', 06:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:31 INFO - 'PWD': '/builds/slave/test', 06:07:31 INFO - 'SHELL': '/bin/bash', 06:07:31 INFO - 'SHLVL': '1', 06:07:31 INFO - 'TERM': 'linux', 06:07:31 INFO - 'TMOUT': '86400', 06:07:31 INFO - 'USER': 'cltbld', 06:07:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:31 INFO - '_': '/tools/buildbot/bin/python'} 06:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07:31 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 06:07:31 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 06:07:31 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 06:07:31 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 06:07:31 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 06:07:31 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 06:07:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:07:33 INFO - Installing collected packages: mozsystemmonitor 06:07:33 INFO - Running setup.py install for mozsystemmonitor 06:07:33 INFO - Successfully installed mozsystemmonitor 06:07:33 INFO - Cleaning up... 06:07:33 INFO - Return code: 0 06:07:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:33 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:33 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 06:07:33 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 06:07:33 INFO - Using env: {'DISPLAY': ':0', 06:07:33 INFO - 'HOME': '/home/cltbld', 06:07:33 INFO - 'LANG': 'en_US.UTF-8', 06:07:33 INFO - 'LANGUAGE': 'en_US:en', 06:07:33 INFO - 'LOGNAME': 'cltbld', 06:07:33 INFO - 'MAIL': '/var/mail/cltbld', 06:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:33 INFO - 'MOZ_NO_REMOTE': '1', 06:07:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:33 INFO - 'NO_EM_RESTART': '1', 06:07:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:33 INFO - 'PWD': '/builds/slave/test', 06:07:33 INFO - 'SHELL': '/bin/bash', 06:07:33 INFO - 'SHLVL': '1', 06:07:33 INFO - 'TERM': 'linux', 06:07:33 INFO - 'TMOUT': '86400', 06:07:33 INFO - 'USER': 'cltbld', 06:07:33 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:33 INFO - '_': '/tools/buildbot/bin/python'} 06:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:33 INFO - Downloading/unpacking jsonschema==2.5.1 06:07:33 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 06:07:33 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 06:07:33 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 06:07:33 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 06:07:33 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 06:07:33 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 06:07:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:35 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 06:07:35 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 06:07:35 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 06:07:35 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 06:07:35 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 06:07:35 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 06:07:35 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:36 INFO - warning: no files found matching '*.txt' 06:07:36 INFO - no previously-included directories found matching 'build' 06:07:36 INFO - no previously-included directories found matching 'dist' 06:07:36 INFO - no previously-included directories found matching '.git*' 06:07:36 INFO - Installing collected packages: jsonschema, functools32 06:07:36 INFO - Running setup.py install for functools32 06:07:36 INFO - warning: no files found matching '*.txt' 06:07:36 INFO - no previously-included directories found matching 'build' 06:07:36 INFO - no previously-included directories found matching 'dist' 06:07:36 INFO - no previously-included directories found matching '.git*' 06:07:36 INFO - Successfully installed jsonschema functools32 06:07:36 INFO - Cleaning up... 06:07:36 INFO - Return code: 0 06:07:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:36 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 06:07:36 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 06:07:36 INFO - Using env: {'DISPLAY': ':0', 06:07:36 INFO - 'HOME': '/home/cltbld', 06:07:36 INFO - 'LANG': 'en_US.UTF-8', 06:07:36 INFO - 'LANGUAGE': 'en_US:en', 06:07:36 INFO - 'LOGNAME': 'cltbld', 06:07:36 INFO - 'MAIL': '/var/mail/cltbld', 06:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:36 INFO - 'MOZ_NO_REMOTE': '1', 06:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:36 INFO - 'NO_EM_RESTART': '1', 06:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:36 INFO - 'PWD': '/builds/slave/test', 06:07:36 INFO - 'SHELL': '/bin/bash', 06:07:36 INFO - 'SHLVL': '1', 06:07:36 INFO - 'TERM': 'linux', 06:07:36 INFO - 'TMOUT': '86400', 06:07:36 INFO - 'USER': 'cltbld', 06:07:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:36 INFO - '_': '/tools/buildbot/bin/python'} 06:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:36 INFO - Cleaning up... 06:07:36 INFO - Return code: 0 06:07:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:36 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:36 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 06:07:36 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 06:07:36 INFO - Using env: {'DISPLAY': ':0', 06:07:36 INFO - 'HOME': '/home/cltbld', 06:07:36 INFO - 'LANG': 'en_US.UTF-8', 06:07:36 INFO - 'LANGUAGE': 'en_US:en', 06:07:36 INFO - 'LOGNAME': 'cltbld', 06:07:36 INFO - 'MAIL': '/var/mail/cltbld', 06:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:36 INFO - 'MOZ_NO_REMOTE': '1', 06:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:36 INFO - 'NO_EM_RESTART': '1', 06:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:36 INFO - 'PWD': '/builds/slave/test', 06:07:36 INFO - 'SHELL': '/bin/bash', 06:07:36 INFO - 'SHLVL': '1', 06:07:36 INFO - 'TERM': 'linux', 06:07:36 INFO - 'TMOUT': '86400', 06:07:36 INFO - 'USER': 'cltbld', 06:07:36 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:36 INFO - '_': '/tools/buildbot/bin/python'} 06:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:36 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:36 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 06:07:36 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 06:07:36 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 06:07:36 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 06:07:36 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 06:07:36 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 06:07:38 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:38 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 06:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:39 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 06:07:39 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 06:07:39 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 06:07:39 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 06:07:39 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 06:07:39 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 06:07:39 INFO - Downloading docopt-0.6.1.tar.gz 06:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:39 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:39 INFO - Running setup.py install for blobuploader 06:07:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:39 INFO - Running setup.py install for requests 06:07:39 INFO - Running setup.py install for docopt 06:07:40 INFO - Successfully installed blobuploader requests docopt 06:07:40 INFO - Cleaning up... 06:07:40 INFO - Return code: 0 06:07:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:40 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 06:07:40 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 06:07:40 INFO - Using env: {'DISPLAY': ':0', 06:07:40 INFO - 'HOME': '/home/cltbld', 06:07:40 INFO - 'LANG': 'en_US.UTF-8', 06:07:40 INFO - 'LANGUAGE': 'en_US:en', 06:07:40 INFO - 'LOGNAME': 'cltbld', 06:07:40 INFO - 'MAIL': '/var/mail/cltbld', 06:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:40 INFO - 'MOZ_NO_REMOTE': '1', 06:07:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:40 INFO - 'NO_EM_RESTART': '1', 06:07:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:40 INFO - 'PWD': '/builds/slave/test', 06:07:40 INFO - 'SHELL': '/bin/bash', 06:07:40 INFO - 'SHLVL': '1', 06:07:40 INFO - 'TERM': 'linux', 06:07:40 INFO - 'TMOUT': '86400', 06:07:40 INFO - 'USER': 'cltbld', 06:07:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:40 INFO - '_': '/tools/buildbot/bin/python'} 06:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:40 INFO - Running setup.py (path:/tmp/pip-k4zPmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:40 INFO - Running setup.py (path:/tmp/pip-xfWDvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:40 INFO - Running setup.py (path:/tmp/pip-Gdeuok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:40 INFO - Running setup.py (path:/tmp/pip-emdDBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:40 INFO - Running setup.py (path:/tmp/pip-dU7mIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:40 INFO - Running setup.py (path:/tmp/pip-hlaIL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:40 INFO - Running setup.py (path:/tmp/pip-pcW6sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:40 INFO - Running setup.py (path:/tmp/pip-_KfZ3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:40 INFO - Running setup.py (path:/tmp/pip-LrePej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:40 INFO - Running setup.py (path:/tmp/pip-ku3Rig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:41 INFO - Running setup.py (path:/tmp/pip-NVt1a6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:41 INFO - Running setup.py (path:/tmp/pip-46sJmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:41 INFO - Running setup.py (path:/tmp/pip-3Gyqr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:41 INFO - Running setup.py (path:/tmp/pip-qFvx7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:41 INFO - Running setup.py (path:/tmp/pip-sNvVAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:41 INFO - Running setup.py (path:/tmp/pip-71xUJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:41 INFO - Running setup.py (path:/tmp/pip-xb1_Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:41 INFO - Running setup.py install for manifestparser 06:07:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:41 INFO - Running setup.py install for mozcrash 06:07:41 INFO - Running setup.py install for mozdebug 06:07:41 INFO - Running setup.py install for mozdevice 06:07:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozfile 06:07:42 INFO - Running setup.py install for mozhttpd 06:07:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozinfo 06:07:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozInstall 06:07:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozleak 06:07:42 INFO - Running setup.py install for mozlog 06:07:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for moznetwork 06:07:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozprocess 06:07:42 INFO - Running setup.py install for mozprofile 06:07:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:42 INFO - Running setup.py install for mozrunner 06:07:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:43 INFO - Running setup.py install for mozscreenshot 06:07:43 INFO - Running setup.py install for moztest 06:07:43 INFO - Running setup.py install for mozversion 06:07:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:43 INFO - Cleaning up... 06:07:43 INFO - Return code: 0 06:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:43 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:43 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 06:07:43 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 06:07:43 INFO - Using env: {'DISPLAY': ':0', 06:07:43 INFO - 'HOME': '/home/cltbld', 06:07:43 INFO - 'LANG': 'en_US.UTF-8', 06:07:43 INFO - 'LANGUAGE': 'en_US:en', 06:07:43 INFO - 'LOGNAME': 'cltbld', 06:07:43 INFO - 'MAIL': '/var/mail/cltbld', 06:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:43 INFO - 'MOZ_NO_REMOTE': '1', 06:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:43 INFO - 'NO_EM_RESTART': '1', 06:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:43 INFO - 'PWD': '/builds/slave/test', 06:07:43 INFO - 'SHELL': '/bin/bash', 06:07:43 INFO - 'SHLVL': '1', 06:07:43 INFO - 'TERM': 'linux', 06:07:43 INFO - 'TMOUT': '86400', 06:07:43 INFO - 'USER': 'cltbld', 06:07:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:43 INFO - '_': '/tools/buildbot/bin/python'} 06:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:43 INFO - Running setup.py (path:/tmp/pip-dOs1Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:43 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)) 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:43 INFO - Running setup.py (path:/tmp/pip-WQw8lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:43 INFO - Running setup.py (path:/tmp/pip-dO7RzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:43 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)) 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:43 INFO - Running setup.py (path:/tmp/pip-LBNxoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:43 INFO - Running setup.py (path:/tmp/pip-Db0Jpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:43 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)) 06:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:43 INFO - Running setup.py (path:/tmp/pip-YjX8eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:44 INFO - Running setup.py (path:/tmp/pip-1UDMAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:44 INFO - Running setup.py (path:/tmp/pip-aTnX1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:44 INFO - Running setup.py (path:/tmp/pip-fqBfh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:44 INFO - Running setup.py (path:/tmp/pip-M1w6W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:44 INFO - Running setup.py (path:/tmp/pip-4M6w2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:44 INFO - Running setup.py (path:/tmp/pip-m631ZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:44 INFO - Running setup.py (path:/tmp/pip-Rcel5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:44 INFO - Running setup.py (path:/tmp/pip-HjbvU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:44 INFO - Running setup.py (path:/tmp/pip-3eCGx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:44 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:44 INFO - Running setup.py (path:/tmp/pip-wgFkXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 06:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:44 INFO - Running setup.py (path:/tmp/pip-2wA0xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:44 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)) 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:44 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)) 06:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:46 INFO - Downloading blessings-1.6.tar.gz 06:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:46 INFO - Installing collected packages: blessings 06:07:46 INFO - Running setup.py install for blessings 06:07:47 INFO - Successfully installed blessings 06:07:47 INFO - Cleaning up... 06:07:47 INFO - Return code: 0 06:07:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:47 INFO - Reading from file tmpfile_stdout 06:07:47 INFO - Current package versions: 06:07:47 INFO - argparse == 1.2.1 06:07:47 INFO - blessings == 1.6 06:07:47 INFO - blobuploader == 1.2.4 06:07:47 INFO - docopt == 0.6.1 06:07:47 INFO - functools32 == 3.2.3-2 06:07:47 INFO - jsonschema == 2.5.1 06:07:47 INFO - manifestparser == 1.1 06:07:47 INFO - mozInstall == 1.12 06:07:47 INFO - mozcrash == 1.0 06:07:47 INFO - mozdebug == 0.1 06:07:47 INFO - mozdevice == 0.49 06:07:47 INFO - mozfile == 1.2 06:07:47 INFO - mozhttpd == 0.7 06:07:47 INFO - mozinfo == 0.9 06:07:47 INFO - mozleak == 0.1 06:07:47 INFO - mozlog == 3.4 06:07:47 INFO - moznetwork == 0.27 06:07:47 INFO - mozprocess == 0.25 06:07:47 INFO - mozprofile == 0.28 06:07:47 INFO - mozrunner == 6.13 06:07:47 INFO - mozscreenshot == 0.1 06:07:47 INFO - mozsystemmonitor == 0.3 06:07:47 INFO - moztest == 0.8 06:07:47 INFO - mozversion == 1.4 06:07:47 INFO - psutil == 3.1.1 06:07:47 INFO - requests == 1.2.3 06:07:47 INFO - wsgiref == 0.1.2 06:07:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:47 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 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:47 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 06:07:47 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 06:07:47 INFO - Using env: {'DISPLAY': ':0', 06:07:47 INFO - 'HOME': '/home/cltbld', 06:07:47 INFO - 'LANG': 'en_US.UTF-8', 06:07:47 INFO - 'LANGUAGE': 'en_US:en', 06:07:47 INFO - 'LOGNAME': 'cltbld', 06:07:47 INFO - 'MAIL': '/var/mail/cltbld', 06:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:47 INFO - 'MOZ_NO_REMOTE': '1', 06:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:47 INFO - 'NO_EM_RESTART': '1', 06:07:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:47 INFO - 'PWD': '/builds/slave/test', 06:07:47 INFO - 'SHELL': '/bin/bash', 06:07:47 INFO - 'SHLVL': '1', 06:07:47 INFO - 'TERM': 'linux', 06:07:47 INFO - 'TMOUT': '86400', 06:07:47 INFO - 'USER': 'cltbld', 06:07:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:47 INFO - '_': '/tools/buildbot/bin/python'} 06:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 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)) 06:07:47 INFO - Cleaning up... 06:07:47 INFO - Return code: 0 06:07:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16801f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1778a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x191db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, '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': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', '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 06:07:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:07:47 INFO - Using env: {'DISPLAY': ':0', 06:07:47 INFO - 'HOME': '/home/cltbld', 06:07:47 INFO - 'LANG': 'en_US.UTF-8', 06:07:47 INFO - 'LANGUAGE': 'en_US:en', 06:07:47 INFO - 'LOGNAME': 'cltbld', 06:07:47 INFO - 'MAIL': '/var/mail/cltbld', 06:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:47 INFO - 'MOZ_NO_REMOTE': '1', 06:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:07:47 INFO - 'NO_EM_RESTART': '1', 06:07:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:47 INFO - 'PWD': '/builds/slave/test', 06:07:47 INFO - 'SHELL': '/bin/bash', 06:07:47 INFO - 'SHLVL': '1', 06:07:47 INFO - 'TERM': 'linux', 06:07:47 INFO - 'TMOUT': '86400', 06:07:47 INFO - 'USER': 'cltbld', 06:07:47 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:47 INFO - '_': '/tools/buildbot/bin/python'} 06:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:07:47 INFO - Cleaning up... 06:07:47 INFO - Return code: 0 06:07:47 INFO - Running post-action listener: _resource_record_post_action 06:07:47 INFO - Running post-action listener: _start_resource_monitoring 06:07:47 INFO - Starting resource monitoring. 06:07:47 INFO - [mozharness: 2017-02-21 14:07:47.794162Z] Finished create-virtualenv step (success) 06:07:47 INFO - [mozharness: 2017-02-21 14:07:47.794739Z] Running install step. 06:07:47 INFO - Running pre-action listener: _resource_record_pre_action 06:07:47 INFO - Running main action method: install 06:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:48 INFO - Reading from file tmpfile_stdout 06:07:48 INFO - Detecting whether we're running mozinstall >=1.0... 06:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:07:48 INFO - Reading from file tmpfile_stdout 06:07:48 INFO - Output received: 06:07:48 INFO - Usage: mozinstall [options] installer 06:07:48 INFO - Options: 06:07:48 INFO - -h, --help show this help message and exit 06:07:48 INFO - -d DEST, --destination=DEST 06:07:48 INFO - Directory to install application into. [default: 06:07:48 INFO - "/builds/slave/test"] 06:07:48 INFO - --app=APP Application being installed. [default: firefox] 06:07:48 INFO - mkdir: /builds/slave/test/build/application 06:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:08:03 INFO - Reading from file tmpfile_stdout 06:08:03 INFO - Output received: 06:08:03 INFO - /builds/slave/test/build/application/firefox/firefox 06:08:03 INFO - Running post-action listener: _resource_record_post_action 06:08:03 INFO - [mozharness: 2017-02-21 14:08:03.487492Z] Finished install step (success) 06:08:03 INFO - [mozharness: 2017-02-21 14:08:03.487769Z] Running run-tests step. 06:08:03 INFO - Running pre-action listener: _resource_record_pre_action 06:08:03 INFO - Running main action method: run_tests 06:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:03 INFO - Python 2.7.3 06:08:03 INFO - Return code: 0 06:08:03 INFO - grabbing minidump binary from tooltool 06:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:03 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 0x191b290>, '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 0x191bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x191c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:03 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 06:08:03 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 06:08:03 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 06:08:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:03 INFO - Return code: 0 06:08:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:08:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:03 INFO - ENV: RUST_BACKTRACE is now 1 06:08:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:08:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:03 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:08:03 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:08:03 INFO - Using env: {'DISPLAY': ':0', 06:08:03 INFO - 'HOME': '/home/cltbld', 06:08:03 INFO - 'LANG': 'en_US.UTF-8', 06:08:03 INFO - 'LANGUAGE': 'en_US:en', 06:08:03 INFO - 'LOGNAME': 'cltbld', 06:08:03 INFO - 'MAIL': '/var/mail/cltbld', 06:08:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:03 INFO - 'MOZ_NO_REMOTE': '1', 06:08:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:08:03 INFO - 'NO_EM_RESTART': '1', 06:08:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:03 INFO - 'PWD': '/builds/slave/test', 06:08:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:03 INFO - 'RUST_BACKTRACE': '1', 06:08:03 INFO - 'SHELL': '/bin/bash', 06:08:03 INFO - 'SHLVL': '1', 06:08:03 INFO - 'TERM': 'linux', 06:08:03 INFO - 'TMOUT': '86400', 06:08:03 INFO - 'USER': 'cltbld', 06:08:03 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599', 06:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:03 INFO - '_': '/tools/buildbot/bin/python'} 06:08:03 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:08:03 INFO - mozversion application_buildid: 20170221131600 06:08:03 INFO - mozversion application_changeset: b69d4316561aa125612509a202922455d2de03e5 06:08:03 INFO - mozversion application_display_name: Nightly 06:08:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:03 INFO - mozversion application_name: Firefox 06:08:03 INFO - mozversion application_remotingname: firefox 06:08:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:03 INFO - mozversion application_vendor: Mozilla 06:08:03 INFO - mozversion application_version: 54.0a1 06:08:03 INFO - mozversion platform_buildid: 20170221131600 06:08:03 INFO - mozversion platform_changeset: b69d4316561aa125612509a202922455d2de03e5 06:08:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:03 INFO - mozversion platform_version: 54.0a1 06:08:03 INFO - using testdate: 1487686083 06:08:03 INFO - actual date: 1487686083 06:08:03 INFO - starting webserver on 'localhost:37409' 06:08:03 INFO - SUITE-START | Running 2 tests 06:08:03 INFO - TEST-START | dromaeo_css 06:08:03 INFO - operating with platform_type : linux_ 06:08:03 INFO - Initialising browser for dromaeo_css test... 06:08:03 INFO - TEST-INFO | started process 10390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp23_8Ct/profile http://localhost:37409/getInfo.html) 06:08:06 INFO - PROCESS | 10390 | Iterating over profile /tmp/tmp23_8Ct/profile 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/mimeTypes.rdf = Tue Feb 21 2017 06:08:07 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | Stat failure 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/pluginreg.dat = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/places.sqlite = Tue Feb 21 2017 06:08:07 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/prefs.js = Tue Feb 21 2017 06:07:23 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/bookmarkbackups = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/compatibility.ini = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/startupCache = Tue Feb 21 2017 06:08:03 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/addons.json = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/thumbnails = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/gmp = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/frequencyCap.json = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/.parentlock = Tue Feb 21 2017 06:08:03 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/safebrowsing = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/key3.db = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/extensions.ini = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/times.json = Tue Feb 21 2017 06:08:07 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/content-prefs.sqlite = Tue Feb 21 2017 06:08:05 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/minidumps = Tue Feb 21 2017 06:08:03 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/blocklist.xml = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/permissions.sqlite = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/places.sqlite-shm = Tue Feb 21 2017 06:08:05 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/extensions.json = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:07 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:07 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/crashes = Tue Feb 21 2017 06:08:03 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/cache2 = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/secmod.db = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/localstore.rdf = Tue Feb 21 2017 06:07:23 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/directoryLinks.json = Tue Feb 21 2017 06:08:06 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | Stat failure 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/user.js = Tue Feb 21 2017 06:08:03 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/extensions = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/places.sqlite-wal = Tue Feb 21 2017 06:08:08 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/cert8.db = Tue Feb 21 2017 06:08:04 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | __metrics Screen width/height:1600/1200 06:08:08 INFO - PROCESS | 10390 | colorDepth:24 06:08:08 INFO - PROCESS | 10390 | Browser inner width/height: 1024/697 06:08:08 INFO - PROCESS | 10390 | __metrics 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/sessionCheckpoints.json = Tue Feb 21 2017 06:08:07 GMT-0800 (PST) 06:08:08 INFO - PROCESS | 10390 | No birth date. Using mtime. 06:08:08 INFO - PROCESS | 10390 | Using date: /tmp/tmp23_8Ct/profile/webappsstore.sqlite = Tue Feb 21 2017 06:08:07 GMT-0800 (PST) 06:08:08 ERROR - PROCESS | 10390 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 06:08:09 INFO - TEST-INFO | 10390: exit 0 06:08:09 INFO - Browser initialized. 06:08:09 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:09 INFO - TEST-INFO | started process 10519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp23_8Ct/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:21 INFO - PROCESS | 10519 | RSS: Main: 153006080 06:08:21 INFO - PROCESS | 10519 | 06:11:26 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37409/tests/dromaeo/cssquery-ext.html) 06:11:28 INFO - PROCESS | 10519 | RSS: Main: 163385344 06:11:28 INFO - PROCESS | 10519 | 06:13:10 INFO - PROCESS | 10519 | ************************* 06:13:10 INFO - PROCESS | 10519 | A coding exception was thrown and uncaught in a Task. 06:13:10 INFO - PROCESS | 10519 | 06:13:10 ERROR - PROCESS | 10519 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:13:10 INFO - PROCESS | 10519 | Full stack: 06:13:10 INFO - PROCESS | 10519 | ************************* 06:14:30 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-ext.html (next: http://localhost:37409/tests/dromaeo/cssquery-jquery.html) 06:14:31 INFO - PROCESS | 10519 | RSS: Main: 158203904 06:14:31 INFO - PROCESS | 10519 | 06:17:33 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37409/tests/dromaeo/cssquery-mootools.html) 06:17:33 INFO - PROCESS | 10519 | RSS: Main: 168022016 06:17:33 INFO - PROCESS | 10519 | 06:20:35 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37409/tests/dromaeo/cssquery-prototype.html) 06:20:36 INFO - PROCESS | 10519 | RSS: Main: 158666752 06:20:36 INFO - PROCESS | 10519 | 06:23:39 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37409/tests/dromaeo/cssquery-yui.html) 06:23:40 INFO - PROCESS | 10519 | RSS: Main: 165171200 06:23:40 INFO - PROCESS | 10519 | 06:26:42 INFO - PROCESS | 10519 | Cycle 1(1): loaded http://localhost:37409/tests/dromaeo/cssquery-yui.html (next: http://localhost:37409/tests/dromaeo/cssquery-dojo.html) 06:26:43 INFO - PROCESS | 10519 | RSS: Main: 158720000 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | __start_tp_report 06:26:43 INFO - PROCESS | 10519 | _x_x_mozilla_page_load 06:26:43 INFO - PROCESS | 10519 | _x_x_mozilla_page_load_details 06:26:43 INFO - PROCESS | 10519 | |i|pagename|runs| 06:26:43 INFO - PROCESS | 10519 | |0;dojo.html;2577.7680008799207;2584.0826506590147;2585.289045512484;2612.137994461828;2628.934276643085;4749.145153872301;4829.975850120749;4840.346553215315;4841.225403935376;4870.9269360959615;4736.715797052179;4753.144434001878;4821.011692603017;4823.986962737829;4835.202191638376;4222.78886055696;4386.912261754771;4456.777161141946;4541.023679908819;4541.318802179667;4442.844500442485;4478.932816007762;4502.09958008398;4502.4371953505815;4505.819767209307;4281.636060934824;4308.719933204332;4316.460442444694;4362.149380870732;4416.293393057128;4365.476142862845;4369.759663218521;4407.933880991788;4412.691111622188;4417.028253784162;4275.080857615628;4340.197063543254;4348.130373925228;4360.7819609019425;4391.033972526039;4527.049319642879;4583.747893865836;4678.3216433616935;4766.308885211625;4769.809207631691;5169.302144210558;5213.2701421800975;5214.400343960455;5246.396664383607;5256.973715131438;5249.448807875195;5252.422233554306;5253.921191182136;5261.763220655042;5313.7608807603365;4601.28680054592;4643.419572553364;4882.511748825089;4884.877878053077;4887.780049897763;5077.873053173626;5171.689698618095;5198.116320225571;5205.219217117463;5279.868003299948;4908.779104940286;5061.190209566452;5064.215046667772;5073.619478539051;5126;5158.871028224249;5170;5170.069387510284;5197.974010129925;5214.426413094557;4939.975300123477;4993.7503124843615;5151.098557752378;5164.870878227999;5192.610554208374;5015.398152221684;5021.07110184617;5027.773750181251;5043.167877300204;5050.924236136461;4321.891952701145;4350.630196433339;4355.499117601516;4383.868483945486;4430.025394413224;3783.2244389900006;3792.924141517154;3805.7145714071557;3824.0248736572;3833.099221682902;4481.955180448219;4481.955180448219;4490.955090449119;4509.503954564995;4524.457065152203;4946.727929963887;5023.648344615842;5080.364954380702;5110.718910459886;5161.690298582097;4439.911201776011;4444.377787109743;4471.686981911235;4482.036362182146;4488.483824360175;4571.1543364477675;4594.954050459452;4614.561616646413;4617.284320930261;4654.790534425959;4634.536546345474;4673.345731597511;4739.099571081483;4758.262469317261;4790.880227994328;5145.511176438233;5171.560417364416;5182.144946083854;5209.843704688866;5220.634555581073;4784.7607619619575;4810.134175848241;4810.278458231293;4816.422029356359;4860.756962151808;4889.804407823647;4941.184704523714;5043.117454445531;5058.595312374928;5125.487451254845;5130.897382052263;5162.174052151638;5183.50756678115;5187.507186817246;5242.397124330751;5022.246663000433;5030.968651426376;5031.371078615173;5033.043721692867;5087.033463642044;4832.081904438145;4878.829240976691;4939.678920870132;4966.205407134841;4972.751362431936;4840.757962101951;4862.319275301531;4889.682170658896;4900.460949295646;4994.250862370528;4977.054359671651;5019.222020586815;5032.421271553818;5032.899342013213;5056.6207534434325;4708.317293992421;4770;4782.330473733749;4798.352222449925;4925.876853078558 06:26:43 INFO - PROCESS | 10519 | |1;ext.html;530.067081935793;531.0891820527794;533.2454576773864;535.8044313825455;552.7346873500722;38990.41514377283;39914;40002;40090;40146.799266003705;40169.799151004205;40384;40427.59572404275;40581;40622.18755624893;7586.355159811409;7746.341560967325;7747.883781743279;9352.532373381338;9530.571124299424;9368.718938431857;9376.671816486425;9458.148766611004;9465;9473.19477844384;9338.53307334634;9442.291828112884;9466.715998520021;9473.052694730506;9494.66768663097;9316.975132735393;9350.438973661569;9353.485558294291;9392.953035234814;9414.858777118281;9284.210842078432;9302.953485232598;9318.161365477072;9327.580258888367;9361.251099911991;33369.83315083433;33680.83159584211;33688.6631133688;33702.76498847721;33966.6603333966;3301.983490082558;3360.630330663625;3401.5918089829133;3406.812625305607;3445.9655403446145;25273;25301.475544902893;25386.619200712004;25443.61834572483;25564.36089097769;3048.2074660588187;3093.3658599987175;3195.1852277669277;3209.718925173048;3247.7888937219004;221945;223719;223787;223872;224542.99999999837;38432.807835960644;39450;45910.113482977824;46244.76877615591;48368.51631483711;49527.50472495301;49597.504024959286;49717.25424118641;49838.50161498411;50051.24923126083;13039.282839443922;13233.404496797668;13246.337683115806;13249.933750331187;13260.734785304432;10119.190464762802;10286.588536458608;10292.279540432099;10298.279120461646;10317.968203179773;15971.201439927958;15978;16028.519144425685;16031.599210019609;16034.518964431085;6705.228898676617;6802.14973128359;6810.148731408574;6813.829654258683;6892.655367231619;756.6821934787403;767.2595944913186;769.6690423118113;790.9644066017044;792.6750032486652;26049.869750651127;26775.21393794557;27320.45359092807;27322.726772732414;27442.725572744017;922.3635691372931;939.7227817793768;942.4910548303839;956.8756061711933;962.0956301076965;13698.452061917611;14536.491222807152;14559.3448294827;14690.26548672562;14704.852951470562;119205.40397297958;120161.39919300348;120178.3991080039;336376;336424;48630;49042;49202;49257.26114108291;49355.50644493581;1022.5705203814416;1092.4210168610648;1092.8797832238447;1095.517972092293;1191.4876603060593;24690.185413844127;25659.35851603725;26031.088911887993;26227.868860655573;26258.606120908586;40524.79737601293;40888.386674200505;41013.79493102516;41119.38320925129;41168.79415602903;266753;267085;268504;270612;270811;22610.547789044456;23286.534269314856;23317.3004809856;24168.154114605903;24647;33544.48416368733;33695.494567581016;34386.312273754884;34625.30749385049;34631.65368346284;29480.115596532138;29659.258518537208;29699.85150074236;30284.09147725572;30581.23546911345;27847.58228626532;28157.718422816328;28263;28701.138965831058;29800;28764.712352876202;29011.274718132216;29085;29280;31152.532712008884;25757.484850302513;28602.57096143518;29209.415811683222;29316.853415732785;29496.705032949394;22539.30664428416;22595.43511412228;22858;22956.081756729545;23134.652980205647 06:26:43 INFO - PROCESS | 10519 | |2;jquery.html;385.1757239507453;387.20236313194823;394.3670408993566;402.6114799218754;428.3617410059013;12427.192232504882;15985.360585576564;16438.75341869873;17798.73301900471;17809.643807123866;16398.672026559463;17448.91275543624;17514.21186046628;17574.209160587772;17676.911615441935;5045.243213517965;5084.669496482736;5120.948790512094;5282.154855223165;5329.040772660919;5117.232415137741;5183.948160518385;5202.0376230397305;5206.687598744087;5222.033923724104;5114.258432527281;5149.794008239666;5175.792968281265;5189.766460509267;5238.711870847102;5430.8913821723545;5540.9168862467095;5548.056830338852;5550.833474995757;5551.861203469905;5256.763445644955;5411.242426060355;5423.945760542383;5426.213199086111;5490.615656903979;12718.427670754838;12822.012588048774;12873.420696068699;12921.67695807603;17159.742603860952;2121.2575598540543;2126.51090249242;2128.276096361936;2157.4822042709793;2189.5730332585154;9359.251259899193;9441.622335106587;9710.563024663907;11350.829737553944;13421.523632400427;1701.5831121375218;1726.8100508944005;1730.9394171203946;1735.973960390595;1774.5297496163398;75436;76103.61948190223;76223.23776762217;76858.6157069211;77100.99999999943;10440.73898152537;10627.787444251071;10731.678049658523;10847.186461015455;10867.565297388015;11944.820827687592;12473.438695258736;15004.774928376082;15045.322960466805;15063.698726025637;6803.251642319341;6857.588544687334;6874.931250687429;7095.858082838417;7120.893186602205;6547.607143571401;6606.471482281406;6825.863482730318;6838.589684618939;6872.381485666314;3645.9635403645625;3660.2679464107287;3710.2023065041144;3787;3992.7803970781306;1987.6620974434381;1996.7903370146216;2044.7750747417772;2057.2124770935166;2067.131804642054;905.3707673167218;913.3332667153015;927.1099744245465;929.7489677786959;951.2152474208667;14977.326020329112;16501.422450214184;16940.23768930401;16994.32022719102;17338.479845604652;936.6440752514001;942.533445944262;947.1144479911305;949.5756365451822;966.6085235479501;9302.581383837743;9366.625334986524;9561.665341713007;9591.472469014272;9692.030796920393;309620;315834;317686;491584;515843;77018;89643.55178224067;90476.54761726281;90578.54710726404;90681;920.204023519664;930.1163894300306;971.4802580619335;972.3145182646217;972.8443449048118;15460.149691767061;15473.226338683246;16120.596985075466;16416.589585259986;16451.58871028186;37054.258914822094;38760.224795504495;40137.799311003255;40590.9852253696;41593.58406415897;46700.76649616866;49764.25353619771;50406;50511.24233136432;52543;6370.554061215856;6770.661466926733;6855.5543889647015;7294.161171465137;7371;8461.32309415233;10455.111315538265;10480.63317783874;10786.244962852525;10850.620228291971;9235.445873247627;9444.858327125236;9659.275554333593;9804.754881128029;9960.103590676874;9324.440533568008;9559.569819358006;9622.711318660451;9775.706728798148;9981.401115933068;9630.759231019281;9713.514324283899;9724.75688107803;10017.298789084694;10032;7033.402160816388;7090.893636595559;7107.324804143598;7162.319579639926;7226.638668066681;7817.570033648204;9167.143034671306;9296.349255552046;9426.245900328095;9647.1317581418 06:26:43 INFO - PROCESS | 10519 | |3;mootools.html;1306.6863952651368;1320.2870449957024;1322.914010589311;1333.2067419885168;1338.1703343926763;7339.596322202284;7409.888851667216;7581.279778421055;7768.067831860185;7790.532568045915;7671.424643151772;7816.8827467588035;7824.687012519493;7832.37341012719;7846.411519136059;2101.05452546354;2135.306025541697;2135.7330333708287;2136.049457991187;2138.1447421031557;2109.1141720477435;2156.1698745982753;2158.98920505398;2160.2007257314817;2160.6650969099815;2110.525131845338;2119.152339064371;2135.29535253366;2140.315099168267;2140.3685912655806;2108.061912448961;2116.523782149012;2123.193186589086;2129.084493667722;2131.8720876747443;1782.3316256403848;1851.7222416637612;2022.3629556689743;2030.2082187946712;2119.237074653139;7574.204708505583;7858.17489163641;7860.213978602152;7862.056553213593;7881.211878812131;4574.61115805157;4695.413073365829;4700.059988002413;4707.411573553306;4719.244920812688;2771.196353057611;2815.6480439945008;2823.2659508527727;2848.6011958325853;2876.3528206153655;1123.7021240467272;1134.6142311614092;1149.0462915779967;1150.6950658075616;1195.4202211927295;10478.685639430752;10508.632197873038;10514.21143414247;10533.157347412189;10537.051665350155;3940.5074365704286;4086;4150.584941505826;4205.516365617932;4219.725717828332;8128.349732021424;8190.549519776469;8192.836143277102;8205.79485512867;8212.260896519341;4588.541145885385;4610.4467463903875;4615.469221039623;4621.075784843045;4629.328747331618;4125.608067233608;4242.045539753531;4299.290617048215;4333.9133217335475;4371.846985355497;3319.502074688816;3332.3668502984356;3405.812680302607;3418.7265018798435;3430.1081718752803;994.8109859126887;1019.5870672377699;1022.5756311130946;1031.4739482863793;1038.1383451735041;744.2557442557443;759.8594260061906;779.6959185917497;780.4878048780488;782.4640121516779;4677.95322046782;4788.712677239377;4796.424429068465;4970.826021089245;5076.82231121909;527.3724268120924;537.6317222702461;545.1550097349093;546.0825812634812;548.2488990083611;3295.340931813647;3297.884574039897;3313.618933822593;3322.9335413292083;3447.5862896452086;8041.155678653774;8468.32253419725;8682.392232543787;8917.42036767608;9352.766180845398;8908.821823563492;9302;9319;9330.90669093314;9335.626574937063;6946.5137440378685;7085.43316534676;7161.140663120459;7245.673944672502;7405.592692401862;5085.364329458817;5101.974490127525;5111.616628752933;5144.511271429358;5280.234366016983;8338.499690018618;8383.58082095905;8425.662973480992;8511;8595.09751476086;8410.453320534145;8592.527410992205;8626.482411055356;8650;8755.299576034029;6518.413342799171;6593.142891424084;6633.071370008299;6635.834104147435;6652.73389064432;7013.719451222098;7059.399951004222;7106.111736032996;7134.571925684476;7172.067631208119;6399.968000159969;6418.133551970611;6429.678516074084;6450.677466126769;6660.900086498803;6548.3124271950755;6580.80257592273;6597.934020659924;6626.6686665667485;6651.63416012124;5560.916586251128;5704;5706.9714651426475;5708.57185711065;5720.74256658443;4275.187714334266;4315.611594956469;4393.340998850198;4439.089986552814;4505.504394516535;4294.9785251073545;4298.312270036779;4318.611324980767;4347.478302603708;4393.604575588212 06:26:43 INFO - PROCESS | 10519 | |4;prototype.html;351.4148942011551;355.48277256591655;356.35677601928506;372.3799873211104;373.92147648993745;5076.416212135606;5099.974500127503;5106.259592359111;5112.590992720583;5122.385313762345;4996.975015124928;5012.423571289294;5014.448410674823;5035.622328325381;5041.521055499731;3767.5478942526865;3855.8264878080413;3889.892868196932;3901.921961560767;3911.706622003347;3875.709321800862;3876.1897624222497;3884.961150388488;3890.6498415142632;3897.4348719435807;3851.595582463843;3883.766973981556;3891.1633998690418;3901.51231096113;3926.1951299983434;3651.95490525304;3843.442700808395;3898.927794856409;3913.784741839212;3920.666743326821;3384;3400.727941764678;3405.829708514589;3408.199073217792;3417.3165366926714;4919.852404427873;4922.187839006596;4934.827281045182;4947.554720075211;4969.105560999019;4462.620677242438;4464.486584042844;4464.799084041226;4493.258612328962;4498.977505112486;5099.260607211933;5288.629795914288;5352.464753524655;5364.302640656729;5369.852059176361;4011.0774521860135;4022.7184097113213;4031.5162180538523;4035.636792688643;4052.4124002019544;5256.211568264753;5301.64807973968;5632.943670563324;5635.830925072254;5704.429557044304;3668.954767542933;3942.2312649033165;4070.3542697246435;4082.9591704082577;4101.52832424269;4088.4276201331836;4089.26393249218;4094.6314831665295;4107.856225032109;4112.588741125864;4596.747178905192;4605.240135377625;4618.67669263155;4648.930266046012;4683.929741053887;5103;5354.705491198021;5369.865753356197;5382.192671099288;5398.352197736297;1408.2818960867726;1409.3094383751888;1419.2052450627682;1419.361287420665;1427.9286035698174;965.165132160676;1016.217512515359;1020.3775696824931;1024.9282550221412;1031.783325501638;4538.72767633943;4541.9545804542195;4542.409486766765;4542.931856022163;4568.246239370466;5224.320838291073;5298.337707786527;5323.201519772065;5342.679439233659;5359.437259087817;4411.18393097278;4482.439695038121;4567.451905771262;4588.472325682589;4594.816207351735;2815.7614696316477;2826.3183405873474;2833.3141780640485;2835.4754370441533;2837.276494493891;4944.9258261126115;4959.380077490314;4967.528084831935;4975.780933671202;4984.205685953054;5228.660137091077;5440.9934162180125;5445.250148674894;5454.972725136428;5490.615656903979;4917.123482762552;5090.287359769635;5115.948840511547;5117.667351622207;5122.795088196505;5488.231647569263;5511.559075273888;5515.806946756844;5533.529649979797;5534.616345913521;5178.275041494269;5343.519083282523;5422.864428389322;5438.918416223839;5466.316710411199;5044.924326135184;5049.747512624281;5052;5074.188129899198;5107.2594473800345;3817.6564109230303;3830.4062870255157;3842.3083844908183;3851.1334949636107;3871.225754849098;5258.921921006261;5424.945750542444;5498.477644623755;5538.400039974049;5545.195946587803;5225.634205605571;5265.289185959847;5270.657407268515;5278.155495120763;5280.6303558750515;5285.180796976475;5304.15133578641;5316.7075810830775;5339.813106541253;5353.892922141613;5013.924791128209;5204.141316682856;5213.1137706590725;5238.659487133325;5287.603429742861;5310.522053015093;5312.920306195488;5321.441248668834;5343.919841202307;5345.893082138413;5221.947780522298;5224.555912747459;5242.213667949838;5252.317198764289;5284.445133261106 06:26:43 INFO - PROCESS | 10519 | |5;yui.html;656.1764984943894;661.8841702702026;715.7029832619463;737.590637196356;755.4598462099599;12040.57857974971;13079.084464087518;13085.345732713375;13093.738125237489;13113.54102606409;9966.601335946572;12607.873921260785;12798.552050678229;12816.487340506392;13792.586222413318;807.8020884883197;824.9876251856227;826.8346330733847;826.9793255168609;827.5986146718836;799.2646764976236;807.4791759315239;807.7495976247353;808.0787901791962;808.9797755056112;821.3142026407957;821.7945513621595;824.2087595907935;824.7154731617583;826.1944604011101;817.5993763056119;822.6791551294999;825.3562221467295;825.5583262954291;829.0590180145518;596.472122171881;599.9760009599654;601.0983524712931;601.7683191971123;605.4127496328598;13551.983601229947;13604.251766152858;13633.113847599974;13645.385957631832;13762.174269543859;13031.804522932163;13682.439120966184;13698.178109313472;13800.792988105186;13963.79054314186;9904.603815847357;10002.649907253284;10015.248856335731;10053.748656283578;10092.697219083439;3176.8093914365213;3195.5526226328334;3209.4889237746333;3210.181403742054;3218.7746857719967;56088;57262;57367.71316143392;57718.7114064427;58368;10342.431166285776;10391.948040259751;10476.790464190673;10498.475076246157;10626.840597391045;19661.410157695293;19668.213271469267;19676.606467870562;19882.1053052613;20152.193912243638;6441.484681225491;6560.573562718408;6625.271220165778;6675.165604299463;6693.497987650946;3419.4870769384793;3554.360215161296;3563.768355056913;3587.1749497615697;3597.2985267873037;3087.243625311813;3139.3878193752457;3153.274746808247;3157.2896098377682;3167.667394923546;513.0969493691131;517.2680656870486;527.9709615971159;535.1037013003204;538.9248449343585;600.3396264109444;620.5811077522654;627.8179327994922;635.5155012244302;651.3974573519475;8708.433951793113;8994.016641527898;9280.257579393632;9290.005814329139;9511.857322140173;604.1572007050228;633.1009965848506;636.8439732265532;641.3330136657917;642.9035644653246;3958.786331265386;4211.0946146578635;4217.514985776614;4219.894502637459;4329.242382583036;46308.3053754194;49641.50358496441;49889.501104988485;59348;61161.69419152965;47461;50060.749696252016;50442.49557504378;50930.49069509257;52068.47931520712;45353.54646453559;48675.26987095196;48879.26681099786;48884;49185;9145.222656074307;9734.610615575297;9807.85288220663;9840.557174927002;9850.458224797705;31605.52591711172;31856.5221521682;33580.83209583937;33738.662613373555;34190.82904585461;39450;40097.39853902252;40178.1964360717;40774.59225407708;40855.1828963425;11013.449327533752;11281.21031527785;11314.321140731583;11389.088872889983;11579.652610421701;12796.61610151697;12890.388701412323;12918.289494077915;12979.351032448529;13303.467861285828;11795.828050023347;11952.163348565517;12363.258204507758;12740.006098566644;12808.231506109663;12136.575219867262;12228.755424891275;12256.14207005501;12259.080568957543;12773.680657983625;10597.629082982367;11013;11014.834777478505;11036.503357348778;11391.373474458984;9414.364420909;9569.3779904306;9809.656662016796;9897.505124743879;10006.799864002534;8400.83198336042;9533;9757.54812362482;9822.705318840446;10016.248781341283 06:26:43 INFO - PROCESS | 10519 | __end_tp_report 06:26:43 INFO - PROCESS | 10519 | __start_cc_report 06:26:43 INFO - PROCESS | 10519 | _x_x_mozilla_cycle_collect,393 06:26:43 INFO - PROCESS | 10519 | __end_cc_report 06:26:43 INFO - PROCESS | 10519 | __startTimestamp1487687203110__endTimestamp 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | ------- Summary: start ------- 06:26:43 INFO - PROCESS | 10519 | Number of tests: 6 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#0] dojo.html Cycles:165 Average:4729.75 Median:4835.20 stddev:508.43 (10.5%) stddev-sans-first:481.15 06:26:43 INFO - PROCESS | 10519 | Values: 2577.8 2584.1 2585.3 2612.1 2628.9 4749.1 4830.0 4840.3 4841.2 4870.9 4736.7 4753.1 4821.0 4824.0 4835.2 4222.8 4386.9 4456.8 4541.0 4541.3 4442.8 4478.9 4502.1 4502.4 4505.8 4281.6 4308.7 4316.5 4362.1 4416.3 4365.5 4369.8 4407.9 4412.7 4417.0 4275.1 4340.2 4348.1 4360.8 4391.0 4527.0 4583.7 4678.3 4766.3 4769.8 5169.3 5213.3 5214.4 5246.4 5257.0 5249.4 5252.4 5253.9 5261.8 5313.8 4601.3 4643.4 4882.5 4884.9 4887.8 5077.9 5171.7 5198.1 5205.2 5279.9 4908.8 5061.2 5064.2 5073.6 5126.0 5158.9 5170.0 5170.1 5198.0 5214.4 4940.0 4993.8 5151.1 5164.9 5192.6 5015.4 5021.1 5027.8 5043.2 5050.9 4321.9 4350.6 4355.5 4383.9 4430.0 3783.2 3792.9 3805.7 3824.0 3833.1 4482.0 4482.0 4491.0 4509.5 4524.5 4946.7 5023.6 5080.4 5110.7 5161.7 4439.9 4444.4 4471.7 4482.0 4488.5 4571.2 4595.0 4614.6 4617.3 4654.8 4634.5 4673.3 4739.1 4758.3 4790.9 5145.5 5171.6 5182.1 5209.8 5220.6 4784.8 4810.1 4810.3 4816.4 4860.8 4889.8 4941.2 5043.1 5058.6 5125.5 5130.9 5162.2 5183.5 5187.5 5242.4 5022.2 5031.0 5031.4 5033.0 5087.0 4832.1 4878.8 4939.7 4966.2 4972.8 4840.8 4862.3 4889.7 4900.5 4994.3 4977.1 5019.2 5032.4 5032.9 5056.6 4708.3 4770.0 4782.3 4798.4 4925.9 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#1] ext.html Cycles:180 Average:38554.34 Median:18452.56 stddev:63498.68 (344.1%) stddev-sans-first:63612.63 06:26:43 INFO - PROCESS | 10519 | Values: 530.1 531.1 533.2 535.8 552.7 38990.4 39914.0 40002.0 40090.0 40146.8 40169.8 40384.0 40427.6 40581.0 40622.2 7586.4 7746.3 7747.9 9352.5 9530.6 9368.7 9376.7 9458.1 9465.0 9473.2 9338.5 9442.3 9466.7 9473.1 9494.7 9317.0 9350.4 9353.5 9393.0 9414.9 9284.2 9303.0 9318.2 9327.6 9361.3 33369.8 33680.8 33688.7 33702.8 33966.7 3302.0 3360.6 3401.6 3406.8 3446.0 25273.0 25301.5 25386.6 25443.6 25564.4 3048.2 3093.4 3195.2 3209.7 3247.8 221945.0 223719.0 223787.0 223872.0 224543.0 38432.8 39450.0 45910.1 46244.8 48368.5 49527.5 49597.5 49717.3 49838.5 50051.2 13039.3 13233.4 13246.3 13249.9 13260.7 10119.2 10286.6 10292.3 10298.3 10318.0 15971.2 15978.0 16028.5 16031.6 16034.5 6705.2 6802.1 6810.1 6813.8 6892.7 756.7 767.3 769.7 791.0 792.7 26049.9 26775.2 27320.5 27322.7 27442.7 922.4 939.7 942.5 956.9 962.1 13698.5 14536.5 14559.3 14690.3 14704.9 119205.4 120161.4 120178.4 336376.0 336424.0 48630.0 49042.0 49202.0 49257.3 49355.5 1022.6 1092.4 1092.9 1095.5 1191.5 24690.2 25659.4 26031.1 26227.9 26258.6 40524.8 40888.4 41013.8 41119.4 41168.8 266753.0 267085.0 268504.0 270612.0 270811.0 22610.5 23286.5 23317.3 24168.2 24647.0 33544.5 33695.5 34386.3 34625.3 34631.7 29480.1 29659.3 29699.9 30284.1 30581.2 27847.6 28157.7 28263.0 28701.1 29800.0 28764.7 29011.3 29085.0 29280.0 31152.5 25757.5 28602.6 29209.4 29316.9 29496.7 22539.3 22595.4 22858.0 22956.1 23134.7 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#2] jquery.html Cycles:180 Average:24545.60 Median:5195.90 stddev:66731.38 (1284.3%) stddev-sans-first:66893.92 06:26:43 INFO - PROCESS | 10519 | Values: 385.2 387.2 394.4 402.6 428.4 12427.2 15985.4 16438.8 17798.7 17809.6 16398.7 17448.9 17514.2 17574.2 17676.9 5045.2 5084.7 5120.9 5282.2 5329.0 5117.2 5183.9 5202.0 5206.7 5222.0 5114.3 5149.8 5175.8 5189.8 5238.7 5430.9 5540.9 5548.1 5550.8 5551.9 5256.8 5411.2 5423.9 5426.2 5490.6 12718.4 12822.0 12873.4 12921.7 17159.7 2121.3 2126.5 2128.3 2157.5 2189.6 9359.3 9441.6 9710.6 11350.8 13421.5 1701.6 1726.8 1730.9 1736.0 1774.5 75436.0 76103.6 76223.2 76858.6 77101.0 10440.7 10627.8 10731.7 10847.2 10867.6 11944.8 12473.4 15004.8 15045.3 15063.7 6803.3 6857.6 6874.9 7095.9 7120.9 6547.6 6606.5 6825.9 6838.6 6872.4 3646.0 3660.3 3710.2 3787.0 3992.8 1987.7 1996.8 2044.8 2057.2 2067.1 905.4 913.3 927.1 929.7 951.2 14977.3 16501.4 16940.2 16994.3 17338.5 936.6 942.5 947.1 949.6 966.6 9302.6 9366.6 9561.7 9591.5 9692.0 309620.0 315834.0 317686.0 491584.0 515843.0 77018.0 89643.6 90476.5 90578.5 90681.0 920.2 930.1 971.5 972.3 972.8 15460.1 15473.2 16120.6 16416.6 16451.6 37054.3 38760.2 40137.8 40591.0 41593.6 46700.8 49764.3 50406.0 50511.2 52543.0 6370.6 6770.7 6855.6 7294.2 7371.0 8461.3 10455.1 10480.6 10786.2 10850.6 9235.4 9444.9 9659.3 9804.8 9960.1 9324.4 9559.6 9622.7 9775.7 9981.4 9630.8 9713.5 9724.8 10017.3 10032.0 7033.4 7090.9 7107.3 7162.3 7226.6 7817.6 9167.1 9296.3 9426.2 9647.1 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#3] mootools.html Cycles:180 Average:4937.50 Median:4792.57 stddev:2762.42 (57.6%) stddev-sans-first:2756.69 06:26:43 INFO - PROCESS | 10519 | Values: 1306.7 1320.3 1322.9 1333.2 1338.2 7339.6 7409.9 7581.3 7768.1 7790.5 7671.4 7816.9 7824.7 7832.4 7846.4 2101.1 2135.3 2135.7 2136.0 2138.1 2109.1 2156.2 2159.0 2160.2 2160.7 2110.5 2119.2 2135.3 2140.3 2140.4 2108.1 2116.5 2123.2 2129.1 2131.9 1782.3 1851.7 2022.4 2030.2 2119.2 7574.2 7858.2 7860.2 7862.1 7881.2 4574.6 4695.4 4700.1 4707.4 4719.2 2771.2 2815.6 2823.3 2848.6 2876.4 1123.7 1134.6 1149.0 1150.7 1195.4 10478.7 10508.6 10514.2 10533.2 10537.1 3940.5 4086.0 4150.6 4205.5 4219.7 8128.3 8190.5 8192.8 8205.8 8212.3 4588.5 4610.4 4615.5 4621.1 4629.3 4125.6 4242.0 4299.3 4333.9 4371.8 3319.5 3332.4 3405.8 3418.7 3430.1 994.8 1019.6 1022.6 1031.5 1038.1 744.3 759.9 779.7 780.5 782.5 4678.0 4788.7 4796.4 4970.8 5076.8 527.4 537.6 545.2 546.1 548.2 3295.3 3297.9 3313.6 3322.9 3447.6 8041.2 8468.3 8682.4 8917.4 9352.8 8908.8 9302.0 9319.0 9330.9 9335.6 6946.5 7085.4 7161.1 7245.7 7405.6 5085.4 5102.0 5111.6 5144.5 5280.2 8338.5 8383.6 8425.7 8511.0 8595.1 8410.5 8592.5 8626.5 8650.0 8755.3 6518.4 6593.1 6633.1 6635.8 6652.7 7013.7 7059.4 7106.1 7134.6 7172.1 6400.0 6418.1 6429.7 6450.7 6660.9 6548.3 6580.8 6597.9 6626.7 6651.6 5560.9 5704.0 5707.0 5708.6 5720.7 4275.2 4315.6 4393.3 4439.1 4505.5 4295.0 4298.3 4318.6 4347.5 4393.6 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#4] prototype.html Cycles:180 Average:4398.27 Median:4963.45 stddev:1255.60 (25.3%) stddev-sans-first:1221.83 06:26:43 INFO - PROCESS | 10519 | Values: 351.4 355.5 356.4 372.4 373.9 5076.4 5100.0 5106.3 5112.6 5122.4 4997.0 5012.4 5014.4 5035.6 5041.5 3767.5 3855.8 3889.9 3901.9 3911.7 3875.7 3876.2 3885.0 3890.6 3897.4 3851.6 3883.8 3891.2 3901.5 3926.2 3652.0 3843.4 3898.9 3913.8 3920.7 3384.0 3400.7 3405.8 3408.2 3417.3 4919.9 4922.2 4934.8 4947.6 4969.1 4462.6 4464.5 4464.8 4493.3 4499.0 5099.3 5288.6 5352.5 5364.3 5369.9 4011.1 4022.7 4031.5 4035.6 4052.4 5256.2 5301.6 5632.9 5635.8 5704.4 3669.0 3942.2 4070.4 4083.0 4101.5 4088.4 4089.3 4094.6 4107.9 4112.6 4596.7 4605.2 4618.7 4648.9 4683.9 5103.0 5354.7 5369.9 5382.2 5398.4 1408.3 1409.3 1419.2 1419.4 1427.9 965.2 1016.2 1020.4 1024.9 1031.8 4538.7 4542.0 4542.4 4542.9 4568.2 5224.3 5298.3 5323.2 5342.7 5359.4 4411.2 4482.4 4567.5 4588.5 4594.8 2815.8 2826.3 2833.3 2835.5 2837.3 4944.9 4959.4 4967.5 4975.8 4984.2 5228.7 5441.0 5445.3 5455.0 5490.6 4917.1 5090.3 5115.9 5117.7 5122.8 5488.2 5511.6 5515.8 5533.5 5534.6 5178.3 5343.5 5422.9 5438.9 5466.3 5044.9 5049.7 5052.0 5074.2 5107.3 3817.7 3830.4 3842.3 3851.1 3871.2 5258.9 5424.9 5498.5 5538.4 5545.2 5225.6 5265.3 5270.7 5278.2 5280.6 5285.2 5304.2 5316.7 5339.8 5353.9 5013.9 5204.1 5213.1 5238.7 5287.6 5310.5 5312.9 5321.4 5343.9 5345.9 5221.9 5224.6 5242.2 5252.3 5284.4 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - PROCESS | 10519 | [#5] yui.html Cycles:180 Average:13894.85 Median:45830.93 stddev:16074.50 (35.1%) stddev-sans-first:16088.85 06:26:43 INFO - PROCESS | 10519 | Values: 656.2 661.9 715.7 737.6 755.5 12040.6 13079.1 13085.3 13093.7 13113.5 9966.6 12607.9 12798.6 12816.5 13792.6 807.8 825.0 826.8 827.0 827.6 799.3 807.5 807.7 808.1 809.0 821.3 821.8 824.2 824.7 826.2 817.6 822.7 825.4 825.6 829.1 596.5 600.0 601.1 601.8 605.4 13552.0 13604.3 13633.1 13645.4 13762.2 13031.8 13682.4 13698.2 13800.8 13963.8 9904.6 10002.6 10015.2 10053.7 10092.7 3176.8 3195.6 3209.5 3210.2 3218.8 56088.0 57262.0 57367.7 57718.7 58368.0 10342.4 10391.9 10476.8 10498.5 10626.8 19661.4 19668.2 19676.6 19882.1 20152.2 6441.5 6560.6 6625.3 6675.2 6693.5 3419.5 3554.4 3563.8 3587.2 3597.3 3087.2 3139.4 3153.3 3157.3 3167.7 513.1 517.3 528.0 535.1 538.9 600.3 620.6 627.8 635.5 651.4 8708.4 8994.0 9280.3 9290.0 9511.9 604.2 633.1 636.8 641.3 642.9 3958.8 4211.1 4217.5 4219.9 4329.2 46308.3 49641.5 49889.5 59348.0 61161.7 47461.0 50060.7 50442.5 50930.5 52068.5 45353.5 48675.3 48879.3 48884.0 49185.0 9145.2 9734.6 9807.9 9840.6 9850.5 31605.5 31856.5 33580.8 33738.7 34190.8 39450.0 40097.4 40178.2 40774.6 40855.2 11013.4 11281.2 11314.3 11389.1 11579.7 12796.6 12890.4 12918.3 12979.4 13303.5 11795.8 11952.2 12363.3 12740.0 12808.2 12136.6 12228.8 12256.1 12259.1 12773.7 10597.6 11013.0 11014.8 11036.5 11391.4 9414.4 9569.4 9809.7 9897.5 10006.8 8400.8 9533.0 9757.5 9822.7 10016.2 06:26:43 INFO - PROCESS | 10519 | -------- Summary: end -------- 06:26:43 INFO - PROCESS | 10519 | 06:26:43 INFO - TEST-INFO | 10519: exit 0 06:26:43 INFO - TEST-OK | dromaeo_css | took 1120197ms 06:26:43 INFO - TEST-START | kraken 06:26:43 INFO - operating with platform_type : linux_ 06:26:43 INFO - Initialising browser for kraken test... 06:26:43 INFO - TEST-INFO | started process 10898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6YlDZz/profile http://localhost:37409/getInfo.html) 06:26:47 INFO - PROCESS | 10898 | Iterating over profile /tmp/tmp6YlDZz/profile 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/mimeTypes.rdf = Tue Feb 21 2017 06:26:47 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | Stat failure 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/pluginreg.dat = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/places.sqlite = Tue Feb 21 2017 06:26:47 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/prefs.js = Tue Feb 21 2017 06:07:23 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/bookmarkbackups = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/compatibility.ini = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/startupCache = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/addons.json = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:47 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:47 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/thumbnails = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/gmp = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/frequencyCap.json = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/.parentlock = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/safebrowsing = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/key3.db = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/extensions.ini = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/content-prefs.sqlite = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/minidumps = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | __metrics Screen width/height:1600/1200 06:26:48 INFO - PROCESS | 10898 | colorDepth:24 06:26:48 INFO - PROCESS | 10898 | Browser inner width/height: 1024/697 06:26:48 INFO - PROCESS | 10898 | __metrics 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/blocklist.xml = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/permissions.sqlite = Tue Feb 21 2017 06:26:45 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/places.sqlite-shm = Tue Feb 21 2017 06:26:45 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/extensions.json = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/crashes = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/cache2 = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/secmod.db = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/localstore.rdf = Tue Feb 21 2017 06:07:23 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/directoryLinks.json = Tue Feb 21 2017 06:26:46 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | Stat failure 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/user.js = Tue Feb 21 2017 06:26:43 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/extensions = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/places.sqlite-wal = Tue Feb 21 2017 06:26:48 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/cert8.db = Tue Feb 21 2017 06:26:44 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/sessionCheckpoints.json = Tue Feb 21 2017 06:26:47 GMT-0800 (PST) 06:26:48 INFO - PROCESS | 10898 | No birth date. Using mtime. 06:26:48 INFO - PROCESS | 10898 | Using date: /tmp/tmp6YlDZz/profile/webappsstore.sqlite = Tue Feb 21 2017 06:26:47 GMT-0800 (PST) 06:26:49 ERROR - PROCESS | 10898 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 06:26:50 INFO - TEST-INFO | 10898: exit 0 06:26:50 INFO - Browser initialized. 06:26:50 INFO - Running cycle 1/1 for kraken test... 06:26:50 INFO - TEST-INFO | started process 11025 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6YlDZz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:02 INFO - PROCESS | 11025 | RSS: Main: 156860416 06:27:02 INFO - PROCESS | 11025 | 06:27:05 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37409/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:06 INFO - PROCESS | 11025 | RSS: Main: 162209792 06:27:06 INFO - PROCESS | 11025 | 06:27:08 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37409/tests/kraken/driver.html?testName=audio-dft) 06:27:09 INFO - PROCESS | 11025 | RSS: Main: 160759808 06:27:09 INFO - PROCESS | 11025 | 06:27:12 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37409/tests/kraken/driver.html?testName=audio-fft) 06:27:13 INFO - PROCESS | 11025 | RSS: Main: 155226112 06:27:13 INFO - PROCESS | 11025 | 06:27:15 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37409/tests/kraken/driver.html?testName=audio-oscillator) 06:27:15 INFO - PROCESS | 11025 | RSS: Main: 156065792 06:27:15 INFO - PROCESS | 11025 | 06:27:17 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:27:18 INFO - PROCESS | 11025 | RSS: Main: 156327936 06:27:18 INFO - PROCESS | 11025 | 06:27:21 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37409/tests/kraken/driver.html?testName=imaging-darkroom) 06:27:22 INFO - PROCESS | 11025 | RSS: Main: 161312768 06:27:22 INFO - PROCESS | 11025 | 06:27:25 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37409/tests/kraken/driver.html?testName=imaging-desaturate) 06:27:26 INFO - PROCESS | 11025 | RSS: Main: 158298112 06:27:26 INFO - PROCESS | 11025 | 06:27:29 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37409/tests/kraken/driver.html?testName=json-parse-financial) 06:27:30 INFO - PROCESS | 11025 | RSS: Main: 159313920 06:27:30 INFO - PROCESS | 11025 | 06:27:31 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:27:32 INFO - PROCESS | 11025 | RSS: Main: 159637504 06:27:32 INFO - PROCESS | 11025 | 06:27:34 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:27:35 INFO - PROCESS | 11025 | RSS: Main: 159514624 06:27:35 INFO - PROCESS | 11025 | 06:27:37 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:27:37 INFO - PROCESS | 11025 | RSS: Main: 160374784 06:27:37 INFO - PROCESS | 11025 | 06:27:40 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:27:41 INFO - PROCESS | 11025 | RSS: Main: 160595968 06:27:41 INFO - PROCESS | 11025 | 06:27:44 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:27:45 INFO - PROCESS | 11025 | RSS: Main: 160329728 06:27:45 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | Cycle 1(1): loaded http://localhost:37409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37409/tests/kraken/driver.html?testName=ai-astar) 06:27:47 INFO - PROCESS | 11025 | RSS: Main: 161640448 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | __start_tp_report 06:27:47 INFO - PROCESS | 11025 | _x_x_mozilla_page_load 06:27:47 INFO - PROCESS | 11025 | _x_x_mozilla_page_load_details 06:27:47 INFO - PROCESS | 11025 | |i|pagename|runs| 06:27:47 INFO - PROCESS | 11025 | |0;ai-astar;107;115;116;116;117;116;118;117;118;120 06:27:47 INFO - PROCESS | 11025 | |1;audio-beat-detection;152;134;132;179;131;136;127;128;154;131 06:27:47 INFO - PROCESS | 11025 | |2;audio-dft;166;164;167;168;175;163;167;164;162;167 06:27:47 INFO - PROCESS | 11025 | |3;audio-fft;77;77;77;116;79;78;77;80;95;78 06:27:47 INFO - PROCESS | 11025 | |4;audio-oscillator;86;85;117;84;85;85;98;85;84;113 06:27:47 INFO - PROCESS | 11025 | |5;imaging-gaussian-blur;115;114;115;113;115;119;114;113;114;118 06:27:47 INFO - PROCESS | 11025 | |6;imaging-darkroom;150;152;151;151;150;152;151;151;151;152 06:27:47 INFO - PROCESS | 11025 | |7;imaging-desaturate;96;96;95;95;97;96;96;96;96;96 06:27:47 INFO - PROCESS | 11025 | |8;json-parse-financial;67;66;67;68;69;71;69;70;68;68 06:27:47 INFO - PROCESS | 11025 | |9;json-stringify-tinderbox;67;67;66;66;67;65;66;65;68;69 06:27:47 INFO - PROCESS | 11025 | |10;stanford-crypto-aes;88;88;88;87;87;96;91;90;89;93 06:27:47 INFO - PROCESS | 11025 | |11;stanford-crypto-ccm;150;141;149;148;141;141;141;148;196;152 06:27:47 INFO - PROCESS | 11025 | |12;stanford-crypto-pbkdf2;192;188;188;187;188;188;186;189;190;191 06:27:47 INFO - PROCESS | 11025 | |13;stanford-crypto-sha256-iterative;74;77;72;77;72;75;70;71;75;70 06:27:47 INFO - PROCESS | 11025 | __end_tp_report 06:27:47 INFO - PROCESS | 11025 | __start_cc_report 06:27:47 INFO - PROCESS | 11025 | _x_x_mozilla_cycle_collect,891 06:27:47 INFO - PROCESS | 11025 | __end_cc_report 06:27:47 INFO - PROCESS | 11025 | __startTimestamp1487687267447__endTimestamp 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | ------- Summary: start ------- 06:27:47 INFO - PROCESS | 11025 | Number of tests: 14 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.00 stddev:3.46 (3.0%) stddev-sans-first:1.50 06:27:47 INFO - PROCESS | 11025 | Values: 107.0 115.0 116.0 116.0 117.0 116.0 118.0 117.0 118.0 120.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:135.00 stddev:16.50 (12.2%) stddev-sans-first:16.96 06:27:47 INFO - PROCESS | 11025 | Values: 152.0 134.0 132.0 179.0 131.0 136.0 127.0 128.0 154.0 131.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#2] audio-dft Cycles:10 Average:166.30 Median:167.00 stddev:3.65 (2.2%) stddev-sans-first:3.87 06:27:47 INFO - PROCESS | 11025 | Values: 166.0 164.0 167.0 168.0 175.0 163.0 167.0 164.0 162.0 167.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#3] audio-fft Cycles:10 Average:83.40 Median:78.00 stddev:12.69 (16.3%) stddev-sans-first:13.25 06:27:47 INFO - PROCESS | 11025 | Values: 77.0 77.0 77.0 116.0 79.0 78.0 77.0 80.0 95.0 78.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:85.00 stddev:12.74 (15.0%) stddev-sans-first:13.32 06:27:47 INFO - PROCESS | 11025 | Values: 86.0 85.0 117.0 84.0 85.0 85.0 98.0 85.0 84.0 113.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.00 stddev:2.00 (1.7%) stddev-sans-first:2.12 06:27:47 INFO - PROCESS | 11025 | Values: 115.0 114.0 115.0 113.0 115.0 119.0 114.0 113.0 114.0 118.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 06:27:47 INFO - PROCESS | 11025 | Values: 150.0 152.0 151.0 151.0 150.0 152.0 151.0 151.0 151.0 152.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 06:27:47 INFO - PROCESS | 11025 | Values: 96.0 96.0 95.0 95.0 97.0 96.0 96.0 96.0 96.0 96.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:68.50 stddev:1.49 (2.2%) stddev-sans-first:1.51 06:27:47 INFO - PROCESS | 11025 | Values: 67.0 66.0 67.0 68.0 69.0 71.0 69.0 70.0 68.0 68.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 06:27:47 INFO - PROCESS | 11025 | Values: 67.0 67.0 66.0 66.0 67.0 65.0 66.0 65.0 68.0 69.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:89.50 stddev:2.91 (3.2%) stddev-sans-first:3.02 06:27:47 INFO - PROCESS | 11025 | Values: 88.0 88.0 88.0 87.0 87.0 96.0 91.0 90.0 89.0 93.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:148.50 stddev:16.49 (11.1%) stddev-sans-first:17.49 06:27:47 INFO - PROCESS | 11025 | Values: 150.0 141.0 149.0 148.0 141.0 141.0 141.0 148.0 196.0 152.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:188.50 stddev:1.83 (1.0%) stddev-sans-first:1.50 06:27:47 INFO - PROCESS | 11025 | Values: 192.0 188.0 188.0 187.0 188.0 188.0 186.0 189.0 190.0 191.0 06:27:47 INFO - PROCESS | 11025 | 06:27:47 INFO - PROCESS | 11025 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:74.50 stddev:2.67 (3.6%) stddev-sans-first:2.82 06:27:47 INFO - PROCESS | 11025 | Values: 74.0 77.0 72.0 77.0 72.0 75.0 70.0 71.0 75.0 70.0 06:27:47 INFO - PROCESS | 11025 | -------- Summary: end -------- 06:27:47 INFO - PROCESS | 11025 | 06:27:48 INFO - TEST-INFO | 11025: exit 0 06:27:48 INFO - TEST-OK | kraken | took 64686ms 06:27:48 INFO - SUITE-END | took 1184s 06:27:49 INFO - Completed test suite (00:19:45) 06:27:49 INFO - javascript benchmark 06:27:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6290.421249542365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2577.7680008799207, 2584.0826506590147, 2585.289045512484, 2612.137994461828, 2628.934276643085, 4749.145153872301, 4829.975850120749, 4840.346553215315, 4841.225403935376, 4870.9269360959615, 4736.715797052179, 4753.144434001878, 4821.011692603017, 4823.986962737829, 4835.202191638376, 4222.78886055696, 4386.912261754771, 4456.777161141946, 4541.023679908819, 4541.318802179667, 4442.844500442485, 4478.932816007762, 4502.09958008398, 4502.4371953505815, 4505.819767209307, 4281.636060934824, 4308.719933204332, 4316.460442444694, 4362.149380870732, 4416.293393057128, 4365.476142862845, 4369.759663218521, 4407.933880991788, 4412.691111622188, 4417.028253784162, 4275.080857615628, 4340.197063543254, 4348.130373925228, 4360.7819609019425, 4391.033972526039, 4527.049319642879, 4583.747893865836, 4678.3216433616935, 4766.308885211625, 4769.809207631691, 5169.302144210558, 5213.2701421800975, 5214.400343960455, 5246.396664383607, 5256.973715131438, 5249.448807875195, 5252.422233554306, 5253.921191182136, 5261.763220655042, 5313.7608807603365, 4601.28680054592, 4643.419572553364, 4882.511748825089, 4884.877878053077, 4887.780049897763, 5077.873053173626, 5171.689698618095, 5198.116320225571, 5205.219217117463, 5279.868003299948, 4908.779104940286, 5061.190209566452, 5064.215046667772, 5073.619478539051, 5126.0, 5158.871028224249, 5170.0, 5170.069387510284, 5197.974010129925, 5214.426413094557, 4939.975300123477, 4993.7503124843615, 5151.098557752378, 5164.870878227999, 5192.610554208374, 5015.398152221684, 5021.07110184617, 5027.773750181251, 5043.167877300204, 5050.924236136461, 4321.891952701145, 4350.630196433339, 4355.499117601516, 4383.868483945486, 4430.025394413224, 3783.2244389900006, 3792.924141517154, 3805.7145714071557, 3824.0248736572, 3833.099221682902, 4481.955180448219, 4481.955180448219, 4490.955090449119, 4509.503954564995, 4524.457065152203, 4946.727929963887, 5023.648344615842, 5080.364954380702, 5110.718910459886, 5161.690298582097, 4439.911201776011, 4444.377787109743, 4471.686981911235, 4482.036362182146, 4488.483824360175, 4571.1543364477675, 4594.954050459452, 4614.561616646413, 4617.284320930261, 4654.790534425959, 4634.536546345474, 4673.345731597511, 4739.099571081483, 4758.262469317261, 4790.880227994328, 5145.511176438233, 5171.560417364416, 5182.144946083854, 5209.843704688866, 5220.634555581073, 4784.7607619619575, 4810.134175848241, 4810.278458231293, 4816.422029356359, 4860.756962151808, 4889.804407823647, 4941.184704523714, 5043.117454445531, 5058.595312374928, 5125.487451254845, 5130.897382052263, 5162.174052151638, 5183.50756678115, 5187.507186817246, 5242.397124330751, 5022.246663000433, 5030.968651426376, 5031.371078615173, 5033.043721692867, 5087.033463642044, 4832.081904438145, 4878.829240976691, 4939.678920870132, 4966.205407134841, 4972.751362431936, 4840.757962101951, 4862.319275301531, 4889.682170658896, 4900.460949295646, 4994.250862370528, 4977.054359671651, 5019.222020586815, 5032.421271553818, 5032.899342013213, 5056.6207534434325, 4708.317293992421, 4770.0, 4782.330473733749, 4798.352222449925, 4925.876853078558], "value": 4696.161564902664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.067081935793, 531.0891820527794, 533.2454576773864, 535.8044313825455, 552.7346873500722, 38990.41514377283, 39914.0, 40002.0, 40090.0, 40146.799266003705, 40169.799151004205, 40384.0, 40427.59572404275, 40581.0, 40622.18755624893, 7586.355159811409, 7746.341560967325, 7747.883781743279, 9352.532373381338, 9530.571124299424, 9368.718938431857, 9376.671816486425, 9458.148766611004, 9465.0, 9473.19477844384, 9338.53307334634, 9442.291828112884, 9466.715998520021, 9473.052694730506, 9494.66768663097, 9316.975132735393, 9350.438973661569, 9353.485558294291, 9392.953035234814, 9414.858777118281, 9284.210842078432, 9302.953485232598, 9318.161365477072, 9327.580258888367, 9361.251099911991, 33369.83315083433, 33680.83159584211, 33688.6631133688, 33702.76498847721, 33966.6603333966, 3301.983490082558, 3360.630330663625, 3401.5918089829133, 3406.812625305607, 3445.9655403446145, 25273.0, 25301.475544902893, 25386.619200712004, 25443.61834572483, 25564.36089097769, 3048.2074660588187, 3093.3658599987175, 3195.1852277669277, 3209.718925173048, 3247.7888937219004, 221945.0, 223719.0, 223787.0, 223872.0, 224542.99999999837, 38432.807835960644, 39450.0, 45910.113482977824, 46244.76877615591, 48368.51631483711, 49527.50472495301, 49597.504024959286, 49717.25424118641, 49838.50161498411, 50051.24923126083, 13039.282839443922, 13233.404496797668, 13246.337683115806, 13249.933750331187, 13260.734785304432, 10119.190464762802, 10286.588536458608, 10292.279540432099, 10298.279120461646, 10317.968203179773, 15971.201439927958, 15978.0, 16028.519144425685, 16031.599210019609, 16034.518964431085, 6705.228898676617, 6802.14973128359, 6810.148731408574, 6813.829654258683, 6892.655367231619, 756.6821934787403, 767.2595944913186, 769.6690423118113, 790.9644066017044, 792.6750032486652, 26049.869750651127, 26775.21393794557, 27320.45359092807, 27322.726772732414, 27442.725572744017, 922.3635691372931, 939.7227817793768, 942.4910548303839, 956.8756061711933, 962.0956301076965, 13698.452061917611, 14536.491222807152, 14559.3448294827, 14690.26548672562, 14704.852951470562, 119205.40397297958, 120161.39919300348, 120178.3991080039, 336376.0, 336424.0, 48630.0, 49042.0, 49202.0, 49257.26114108291, 49355.50644493581, 1022.5705203814416, 1092.4210168610648, 1092.8797832238447, 1095.517972092293, 1191.4876603060593, 24690.185413844127, 25659.35851603725, 26031.088911887993, 26227.868860655573, 26258.606120908586, 40524.79737601293, 40888.386674200505, 41013.79493102516, 41119.38320925129, 41168.79415602903, 266753.0, 267085.0, 268504.0, 270612.0, 270811.0, 22610.547789044456, 23286.534269314856, 23317.3004809856, 24168.154114605903, 24647.0, 33544.48416368733, 33695.494567581016, 34386.312273754884, 34625.30749385049, 34631.65368346284, 29480.115596532138, 29659.258518537208, 29699.85150074236, 30284.09147725572, 30581.23546911345, 27847.58228626532, 28157.718422816328, 28263.0, 28701.138965831058, 29800.0, 28764.712352876202, 29011.274718132216, 29085.0, 29280.0, 31152.532712008884, 25757.484850302513, 28602.57096143518, 29209.415811683222, 29316.853415732785, 29496.705032949394, 22539.30664428416, 22595.43511412228, 22858.0, 22956.081756729545, 23134.652980205647], "value": 16307.074373500032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.1757239507453, 387.20236313194823, 394.3670408993566, 402.6114799218754, 428.3617410059013, 12427.192232504882, 15985.360585576564, 16438.75341869873, 17798.73301900471, 17809.643807123866, 16398.672026559463, 17448.91275543624, 17514.21186046628, 17574.209160587772, 17676.911615441935, 5045.243213517965, 5084.669496482736, 5120.948790512094, 5282.154855223165, 5329.040772660919, 5117.232415137741, 5183.948160518385, 5202.0376230397305, 5206.687598744087, 5222.033923724104, 5114.258432527281, 5149.794008239666, 5175.792968281265, 5189.766460509267, 5238.711870847102, 5430.8913821723545, 5540.9168862467095, 5548.056830338852, 5550.833474995757, 5551.861203469905, 5256.763445644955, 5411.242426060355, 5423.945760542383, 5426.213199086111, 5490.615656903979, 12718.427670754838, 12822.012588048774, 12873.420696068699, 12921.67695807603, 17159.742603860952, 2121.2575598540543, 2126.51090249242, 2128.276096361936, 2157.4822042709793, 2189.5730332585154, 9359.251259899193, 9441.622335106587, 9710.563024663907, 11350.829737553944, 13421.523632400427, 1701.5831121375218, 1726.8100508944005, 1730.9394171203946, 1735.973960390595, 1774.5297496163398, 75436.0, 76103.61948190223, 76223.23776762217, 76858.6157069211, 77100.99999999943, 10440.73898152537, 10627.787444251071, 10731.678049658523, 10847.186461015455, 10867.565297388015, 11944.820827687592, 12473.438695258736, 15004.774928376082, 15045.322960466805, 15063.698726025637, 6803.251642319341, 6857.588544687334, 6874.931250687429, 7095.858082838417, 7120.893186602205, 6547.607143571401, 6606.471482281406, 6825.863482730318, 6838.589684618939, 6872.381485666314, 3645.9635403645625, 3660.2679464107287, 3710.2023065041144, 3787.0, 3992.7803970781306, 1987.6620974434381, 1996.7903370146216, 2044.7750747417772, 2057.2124770935166, 2067.131804642054, 905.3707673167218, 913.3332667153015, 927.1099744245465, 929.7489677786959, 951.2152474208667, 14977.326020329112, 16501.422450214184, 16940.23768930401, 16994.32022719102, 17338.479845604652, 936.6440752514001, 942.533445944262, 947.1144479911305, 949.5756365451822, 966.6085235479501, 9302.581383837743, 9366.625334986524, 9561.665341713007, 9591.472469014272, 9692.030796920393, 309620.0, 315834.0, 317686.0, 491584.0, 515843.0, 77018.0, 89643.55178224067, 90476.54761726281, 90578.54710726404, 90681.0, 920.204023519664, 930.1163894300306, 971.4802580619335, 972.3145182646217, 972.8443449048118, 15460.149691767061, 15473.226338683246, 16120.596985075466, 16416.589585259986, 16451.58871028186, 37054.258914822094, 38760.224795504495, 40137.799311003255, 40590.9852253696, 41593.58406415897, 46700.76649616866, 49764.25353619771, 50406.0, 50511.24233136432, 52543.0, 6370.554061215856, 6770.661466926733, 6855.5543889647015, 7294.161171465137, 7371.0, 8461.32309415233, 10455.111315538265, 10480.63317783874, 10786.244962852525, 10850.620228291971, 9235.445873247627, 9444.858327125236, 9659.275554333593, 9804.754881128029, 9960.103590676874, 9324.440533568008, 9559.569819358006, 9622.711318660451, 9775.706728798148, 9981.401115933068, 9630.759231019281, 9713.514324283899, 9724.75688107803, 10017.298789084694, 10032.0, 7033.402160816388, 7090.893636595559, 7107.324804143598, 7162.319579639926, 7226.638668066681, 7817.570033648204, 9167.143034671306, 9296.349255552046, 9426.245900328095, 9647.1317581418], "value": 8140.905795354377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.6863952651368, 1320.2870449957024, 1322.914010589311, 1333.2067419885168, 1338.1703343926763, 7339.596322202284, 7409.888851667216, 7581.279778421055, 7768.067831860185, 7790.532568045915, 7671.424643151772, 7816.8827467588035, 7824.687012519493, 7832.37341012719, 7846.411519136059, 2101.05452546354, 2135.306025541697, 2135.7330333708287, 2136.049457991187, 2138.1447421031557, 2109.1141720477435, 2156.1698745982753, 2158.98920505398, 2160.2007257314817, 2160.6650969099815, 2110.525131845338, 2119.152339064371, 2135.29535253366, 2140.315099168267, 2140.3685912655806, 2108.061912448961, 2116.523782149012, 2123.193186589086, 2129.084493667722, 2131.8720876747443, 1782.3316256403848, 1851.7222416637612, 2022.3629556689743, 2030.2082187946712, 2119.237074653139, 7574.204708505583, 7858.17489163641, 7860.213978602152, 7862.056553213593, 7881.211878812131, 4574.61115805157, 4695.413073365829, 4700.059988002413, 4707.411573553306, 4719.244920812688, 2771.196353057611, 2815.6480439945008, 2823.2659508527727, 2848.6011958325853, 2876.3528206153655, 1123.7021240467272, 1134.6142311614092, 1149.0462915779967, 1150.6950658075616, 1195.4202211927295, 10478.685639430752, 10508.632197873038, 10514.21143414247, 10533.157347412189, 10537.051665350155, 3940.5074365704286, 4086.0, 4150.584941505826, 4205.516365617932, 4219.725717828332, 8128.349732021424, 8190.549519776469, 8192.836143277102, 8205.79485512867, 8212.260896519341, 4588.541145885385, 4610.4467463903875, 4615.469221039623, 4621.075784843045, 4629.328747331618, 4125.608067233608, 4242.045539753531, 4299.290617048215, 4333.9133217335475, 4371.846985355497, 3319.502074688816, 3332.3668502984356, 3405.812680302607, 3418.7265018798435, 3430.1081718752803, 994.8109859126887, 1019.5870672377699, 1022.5756311130946, 1031.4739482863793, 1038.1383451735041, 744.2557442557443, 759.8594260061906, 779.6959185917497, 780.4878048780488, 782.4640121516779, 4677.95322046782, 4788.712677239377, 4796.424429068465, 4970.826021089245, 5076.82231121909, 527.3724268120924, 537.6317222702461, 545.1550097349093, 546.0825812634812, 548.2488990083611, 3295.340931813647, 3297.884574039897, 3313.618933822593, 3322.9335413292083, 3447.5862896452086, 8041.155678653774, 8468.32253419725, 8682.392232543787, 8917.42036767608, 9352.766180845398, 8908.821823563492, 9302.0, 9319.0, 9330.90669093314, 9335.626574937063, 6946.5137440378685, 7085.43316534676, 7161.140663120459, 7245.673944672502, 7405.592692401862, 5085.364329458817, 5101.974490127525, 5111.616628752933, 5144.511271429358, 5280.234366016983, 8338.499690018618, 8383.58082095905, 8425.662973480992, 8511.0, 8595.09751476086, 8410.453320534145, 8592.527410992205, 8626.482411055356, 8650.0, 8755.299576034029, 6518.413342799171, 6593.142891424084, 6633.071370008299, 6635.834104147435, 6652.73389064432, 7013.719451222098, 7059.399951004222, 7106.111736032996, 7134.571925684476, 7172.067631208119, 6399.968000159969, 6418.133551970611, 6429.678516074084, 6450.677466126769, 6660.900086498803, 6548.3124271950755, 6580.80257592273, 6597.934020659924, 6626.6686665667485, 6651.63416012124, 5560.916586251128, 5704.0, 5706.9714651426475, 5708.57185711065, 5720.74256658443, 4275.187714334266, 4315.611594956469, 4393.340998850198, 4439.089986552814, 4505.504394516535, 4294.9785251073545, 4298.312270036779, 4318.611324980767, 4347.478302603708, 4393.604575588212], "value": 3945.1089985272642, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.4148942011551, 355.48277256591655, 356.35677601928506, 372.3799873211104, 373.92147648993745, 5076.416212135606, 5099.974500127503, 5106.259592359111, 5112.590992720583, 5122.385313762345, 4996.975015124928, 5012.423571289294, 5014.448410674823, 5035.622328325381, 5041.521055499731, 3767.5478942526865, 3855.8264878080413, 3889.892868196932, 3901.921961560767, 3911.706622003347, 3875.709321800862, 3876.1897624222497, 3884.961150388488, 3890.6498415142632, 3897.4348719435807, 3851.595582463843, 3883.766973981556, 3891.1633998690418, 3901.51231096113, 3926.1951299983434, 3651.95490525304, 3843.442700808395, 3898.927794856409, 3913.784741839212, 3920.666743326821, 3384.0, 3400.727941764678, 3405.829708514589, 3408.199073217792, 3417.3165366926714, 4919.852404427873, 4922.187839006596, 4934.827281045182, 4947.554720075211, 4969.105560999019, 4462.620677242438, 4464.486584042844, 4464.799084041226, 4493.258612328962, 4498.977505112486, 5099.260607211933, 5288.629795914288, 5352.464753524655, 5364.302640656729, 5369.852059176361, 4011.0774521860135, 4022.7184097113213, 4031.5162180538523, 4035.636792688643, 4052.4124002019544, 5256.211568264753, 5301.64807973968, 5632.943670563324, 5635.830925072254, 5704.429557044304, 3668.954767542933, 3942.2312649033165, 4070.3542697246435, 4082.9591704082577, 4101.52832424269, 4088.4276201331836, 4089.26393249218, 4094.6314831665295, 4107.856225032109, 4112.588741125864, 4596.747178905192, 4605.240135377625, 4618.67669263155, 4648.930266046012, 4683.929741053887, 5103.0, 5354.705491198021, 5369.865753356197, 5382.192671099288, 5398.352197736297, 1408.2818960867726, 1409.3094383751888, 1419.2052450627682, 1419.361287420665, 1427.9286035698174, 965.165132160676, 1016.217512515359, 1020.3775696824931, 1024.9282550221412, 1031.783325501638, 4538.72767633943, 4541.9545804542195, 4542.409486766765, 4542.931856022163, 4568.246239370466, 5224.320838291073, 5298.337707786527, 5323.201519772065, 5342.679439233659, 5359.437259087817, 4411.18393097278, 4482.439695038121, 4567.451905771262, 4588.472325682589, 4594.816207351735, 2815.7614696316477, 2826.3183405873474, 2833.3141780640485, 2835.4754370441533, 2837.276494493891, 4944.9258261126115, 4959.380077490314, 4967.528084831935, 4975.780933671202, 4984.205685953054, 5228.660137091077, 5440.9934162180125, 5445.250148674894, 5454.972725136428, 5490.615656903979, 4917.123482762552, 5090.287359769635, 5115.948840511547, 5117.667351622207, 5122.795088196505, 5488.231647569263, 5511.559075273888, 5515.806946756844, 5533.529649979797, 5534.616345913521, 5178.275041494269, 5343.519083282523, 5422.864428389322, 5438.918416223839, 5466.316710411199, 5044.924326135184, 5049.747512624281, 5052.0, 5074.188129899198, 5107.2594473800345, 3817.6564109230303, 3830.4062870255157, 3842.3083844908183, 3851.1334949636107, 3871.225754849098, 5258.921921006261, 5424.945750542444, 5498.477644623755, 5538.400039974049, 5545.195946587803, 5225.634205605571, 5265.289185959847, 5270.657407268515, 5278.155495120763, 5280.6303558750515, 5285.180796976475, 5304.15133578641, 5316.7075810830775, 5339.813106541253, 5353.892922141613, 5013.924791128209, 5204.141316682856, 5213.1137706590725, 5238.659487133325, 5287.603429742861, 5310.522053015093, 5312.920306195488, 5321.441248668834, 5343.919841202307, 5345.893082138413, 5221.947780522298, 5224.555912747459, 5242.213667949838, 5252.317198764289, 5284.445133261106], "value": 4021.4582758802617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.1764984943894, 661.8841702702026, 715.7029832619463, 737.590637196356, 755.4598462099599, 12040.57857974971, 13079.084464087518, 13085.345732713375, 13093.738125237489, 13113.54102606409, 9966.601335946572, 12607.873921260785, 12798.552050678229, 12816.487340506392, 13792.586222413318, 807.8020884883197, 824.9876251856227, 826.8346330733847, 826.9793255168609, 827.5986146718836, 799.2646764976236, 807.4791759315239, 807.7495976247353, 808.0787901791962, 808.9797755056112, 821.3142026407957, 821.7945513621595, 824.2087595907935, 824.7154731617583, 826.1944604011101, 817.5993763056119, 822.6791551294999, 825.3562221467295, 825.5583262954291, 829.0590180145518, 596.472122171881, 599.9760009599654, 601.0983524712931, 601.7683191971123, 605.4127496328598, 13551.983601229947, 13604.251766152858, 13633.113847599974, 13645.385957631832, 13762.174269543859, 13031.804522932163, 13682.439120966184, 13698.178109313472, 13800.792988105186, 13963.79054314186, 9904.603815847357, 10002.649907253284, 10015.248856335731, 10053.748656283578, 10092.697219083439, 3176.8093914365213, 3195.5526226328334, 3209.4889237746333, 3210.181403742054, 3218.7746857719967, 56088.0, 57262.0, 57367.71316143392, 57718.7114064427, 58368.0, 10342.431166285776, 10391.948040259751, 10476.790464190673, 10498.475076246157, 10626.840597391045, 19661.410157695293, 19668.213271469267, 19676.606467870562, 19882.1053052613, 20152.193912243638, 6441.484681225491, 6560.573562718408, 6625.271220165778, 6675.165604299463, 6693.497987650946, 3419.4870769384793, 3554.360215161296, 3563.768355056913, 3587.1749497615697, 3597.2985267873037, 3087.243625311813, 3139.3878193752457, 3153.274746808247, 3157.2896098377682, 3167.667394923546, 513.0969493691131, 517.2680656870486, 527.9709615971159, 535.1037013003204, 538.9248449343585, 600.3396264109444, 620.5811077522654, 627.8179327994922, 635.5155012244302, 651.3974573519475, 8708.433951793113, 8994.016641527898, 9280.257579393632, 9290.005814329139, 9511.857322140173, 604.1572007050228, 633.1009965848506, 636.8439732265532, 641.3330136657917, 642.9035644653246, 3958.786331265386, 4211.0946146578635, 4217.514985776614, 4219.894502637459, 4329.242382583036, 46308.3053754194, 49641.50358496441, 49889.501104988485, 59348.0, 61161.69419152965, 47461.0, 50060.749696252016, 50442.49557504378, 50930.49069509257, 52068.47931520712, 45353.54646453559, 48675.26987095196, 48879.26681099786, 48884.0, 49185.0, 9145.222656074307, 9734.610615575297, 9807.85288220663, 9840.557174927002, 9850.458224797705, 31605.52591711172, 31856.5221521682, 33580.83209583937, 33738.662613373555, 34190.82904585461, 39450.0, 40097.39853902252, 40178.1964360717, 40774.59225407708, 40855.1828963425, 11013.449327533752, 11281.21031527785, 11314.321140731583, 11389.088872889983, 11579.652610421701, 12796.61610151697, 12890.388701412323, 12918.289494077915, 12979.351032448529, 13303.467861285828, 11795.828050023347, 11952.163348565517, 12363.258204507758, 12740.006098566644, 12808.231506109663, 12136.575219867262, 12228.755424891275, 12256.14207005501, 12259.080568957543, 12773.680657983625, 10597.629082982367, 11013.0, 11014.834777478505, 11036.503357348778, 11391.373474458984, 9414.364420909, 9569.3779904306, 9809.656662016796, 9897.505124743879, 10006.799864002534, 8400.83198336042, 9533.0, 9757.54812362482, 9822.705318840446, 10016.248781341283], "value": 6263.2302015367, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 116.0, 116.0, 117.0, 116.0, 118.0, 117.0, 118.0, 120.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 134.0, 132.0, 179.0, 131.0, 136.0, 127.0, 128.0, 154.0, 131.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 167.0, 168.0, 175.0, 163.0, 167.0, 164.0, 162.0, 167.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 116.0, 79.0, 78.0, 77.0, 80.0, 95.0, 78.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 117.0, 84.0, 85.0, 85.0, 98.0, 85.0, 84.0, 113.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 113.0, 115.0, 119.0, 114.0, 113.0, 114.0, 118.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 151.0, 151.0, 150.0, 152.0, 151.0, 151.0, 151.0, 152.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 68.0, 69.0, 71.0, 69.0, 70.0, 68.0, 68.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 67.0, 65.0, 66.0, 65.0, 68.0, 69.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 87.0, 87.0, 96.0, 91.0, 90.0, 89.0, 93.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 141.0, 149.0, 148.0, 141.0, 141.0, 141.0, 148.0, 196.0, 152.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 188.0, 188.0, 187.0, 188.0, 188.0, 186.0, 189.0, 190.0, 191.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 72.0, 77.0, 72.0, 75.0, 70.0, 71.0, 75.0, 70.0], "value": 73.3, "unit": "score"}]}]} 06:27:49 INFO - Return code: 0 06:27:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:27:49 ERROR - # TBPL SUCCESS # 06:27:49 INFO - Running post-action listener: _resource_record_post_action 06:27:49 INFO - [mozharness: 2017-02-21 14:27:49.242540Z] Finished run-tests step (success) 06:27:49 INFO - Running post-run listener: _resource_record_post_run 06:27:49 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 368685056; Read time: 16; Write time: 200864 06:27:49 INFO - TinderboxPrint: CPU usage
13.5% 06:27:49 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:27:49 INFO - TinderboxPrint: I/O write bytes / time
368,685,056 / 200,864 06:27:49 INFO - TinderboxPrint: CPU idle
8,292.1 (86.5%) 06:27:49 INFO - TinderboxPrint: CPU user
1,235.5 (12.9%) 06:27:49 INFO - TinderboxPrint: Swap in / out
0 / 0 06:27:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 112861184; Read time: 0; Write time: 90856 06:27:49 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 255823872; Read time: 0; Write time: 110008 06:27:49 INFO - Running post-run listener: _upload_blobber_files 06:27:49 INFO - Blob upload gear active. 06:27:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:27:49 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 06:27:49 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'] 06:27:49 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 06:27:50 INFO - (blobuploader) - INFO - Open directory for files ... 06:27:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:51 INFO - (blobuploader) - INFO - Done attempting. 06:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:54 INFO - (blobuploader) - INFO - Done attempting. 06:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:55 INFO - (blobuploader) - INFO - Done attempting. 06:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:56 INFO - (blobuploader) - INFO - Done attempting. 06:27:56 INFO - (blobuploader) - INFO - Iteration through files over. 06:27:56 INFO - Return code: 0 06:27:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:27:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:27:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5d945f02c82b775857855545ce555715c7ffa41953efd98494ac56393c6158abcb646763615bde3acf14cf4dbeee1932d2aa20c7feeacd1ff307b32f6141520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a88a5e1b5a3b0f2e24c82ac8c8163e2931cbbe2000efa1aa1d60a8dcaf7c28a527b6f8e454b3f8f30268b997d0226c18dee40d76e8a191ec30c87b6ca0f17971", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371e502fd684bd4d0a03e32a6844be8c4e01ebf98bdccf3ee7d49d7fa96fda6fc8fcbd88111d870b7ae69517ac2b83f5937373dc30b58017da05510ee68e8c18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03390be23f40cb61e478c6983ca5a695f8d48d4f892363b158e315e5a36a5a2e9f6fbded6849e8d34fac35110458bb7d52b31df71f7d8a9b9ab12cc7199632a8"} 06:27:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:27:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:27:56 INFO - Contents: 06:27:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5d945f02c82b775857855545ce555715c7ffa41953efd98494ac56393c6158abcb646763615bde3acf14cf4dbeee1932d2aa20c7feeacd1ff307b32f6141520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a88a5e1b5a3b0f2e24c82ac8c8163e2931cbbe2000efa1aa1d60a8dcaf7c28a527b6f8e454b3f8f30268b997d0226c18dee40d76e8a191ec30c87b6ca0f17971", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371e502fd684bd4d0a03e32a6844be8c4e01ebf98bdccf3ee7d49d7fa96fda6fc8fcbd88111d870b7ae69517ac2b83f5937373dc30b58017da05510ee68e8c18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03390be23f40cb61e478c6983ca5a695f8d48d4f892363b158e315e5a36a5a2e9f6fbded6849e8d34fac35110458bb7d52b31df71f7d8a9b9ab12cc7199632a8"} 06:27:56 INFO - Running post-run listener: copy_logs_to_upload_dir 06:27:56 INFO - Copying logs to upload dir... 06:27:56 INFO - mkdir: /builds/slave/test/build/upload/logs 06:27:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.245787 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-02-21 06:27:56.237199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:27:56.240381) ========= 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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5d945f02c82b775857855545ce555715c7ffa41953efd98494ac56393c6158abcb646763615bde3acf14cf4dbeee1932d2aa20c7feeacd1ff307b32f6141520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a88a5e1b5a3b0f2e24c82ac8c8163e2931cbbe2000efa1aa1d60a8dcaf7c28a527b6f8e454b3f8f30268b997d0226c18dee40d76e8a191ec30c87b6ca0f17971", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371e502fd684bd4d0a03e32a6844be8c4e01ebf98bdccf3ee7d49d7fa96fda6fc8fcbd88111d870b7ae69517ac2b83f5937373dc30b58017da05510ee68e8c18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03390be23f40cb61e478c6983ca5a695f8d48d4f892363b158e315e5a36a5a2e9f6fbded6849e8d34fac35110458bb7d52b31df71f7d8a9b9ab12cc7199632a8"} build_url:https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/YxPaR-_0Qx6OLVtFVJE4ZA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e5d945f02c82b775857855545ce555715c7ffa41953efd98494ac56393c6158abcb646763615bde3acf14cf4dbeee1932d2aa20c7feeacd1ff307b32f6141520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a88a5e1b5a3b0f2e24c82ac8c8163e2931cbbe2000efa1aa1d60a8dcaf7c28a527b6f8e454b3f8f30268b997d0226c18dee40d76e8a191ec30c87b6ca0f17971", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/371e502fd684bd4d0a03e32a6844be8c4e01ebf98bdccf3ee7d49d7fa96fda6fc8fcbd88111d870b7ae69517ac2b83f5937373dc30b58017da05510ee68e8c18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/03390be23f40cb61e478c6983ca5a695f8d48d4f892363b158e315e5a36a5a2e9f6fbded6849e8d34fac35110458bb7d52b31df71f7d8a9b9ab12cc7199632a8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:27:56.265204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:27:56.265517) ========= 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=41a7e32711b8e08598a63237000001d6-1487686015.15200-836912599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004395 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:27:56.350573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:27:56.350893) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:27:56.351198) ========= ========= Total master_lag: 0.16 =========