builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1487249102.5 results: success (0) revision: 8c8b54b13be7ec12cb8e104b772162a80b524497 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.503630) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.504156) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.504474) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.523377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.523713) ========= 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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 04:45:03-- 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% 45.5M=0s 2017-02-16 04:45:03 (45.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.656398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.656733) ========= 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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019701 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:02.709245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 04:45:02.710075) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c8b54b13be7ec12cb8e104b772162a80b524497 --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 8c8b54b13be7ec12cb8e104b772162a80b524497 --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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 04:45:03,306 truncating revision to first 12 chars 2017-02-16 04:45:03,306 Setting DEBUG logging. 2017-02-16 04:45:03,306 attempt 1/10 2017-02-16 04:45:03,306 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c8b54b13be7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 04:45:04,428 unpacking tar archive at: mozilla-central-8c8b54b13be7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.439919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 04:45:04.172494) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:04.173237) ========= script_repo_revision: 8c8b54b13be7ec12cb8e104b772162a80b524497 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:04.174144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:04.174894) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 04:45:04.186448) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-16 04:45:04.186764) ========= /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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:45:04 INFO - MultiFileLogger online at 20170216 04:45:04 in /builds/slave/test 04:45:04 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 04:45:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:45:04 INFO - {'append_to_log': False, 04:45:04 INFO - 'base_work_dir': '/builds/slave/test', 04:45:04 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:45:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:45:04 INFO - 'branch': 'Firefox-Non-PGO', 04:45:04 INFO - 'buildbot_json_path': 'buildprops.json', 04:45:04 INFO - 'config_files': ('talos/linux_config.py',), 04:45:04 INFO - 'default_actions': ('clobber', 04:45:04 INFO - 'read-buildbot-config', 04:45:04 INFO - 'download-and-extract', 04:45:04 INFO - 'populate-webroot', 04:45:04 INFO - 'create-virtualenv', 04:45:04 INFO - 'install', 04:45:04 INFO - 'run-tests'), 04:45:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:45:04 INFO - 'download_minidump_stackwalk': True, 04:45:04 INFO - 'download_symbols': 'ondemand', 04:45:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:45:04 INFO - 'tooltool.py': '/tools/tooltool.py', 04:45:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:45:04 INFO - '/tools/misc-python/virtualenv.py')}, 04:45:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:45:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:45:04 INFO - 'installer_path': 'installer.exe', 04:45:04 INFO - 'log_level': 'info', 04:45:04 INFO - 'log_name': 'talos', 04:45:04 INFO - 'log_to_console': True, 04:45:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:45:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:45:04 INFO - 'opt_config_files': (), 04:45:04 INFO - 'pip_index': False, 04:45:04 INFO - 'sps_profile': False, 04:45:04 INFO - 'sps_profile_interval': 0, 04:45:04 INFO - 'suite': 'dromaeojs-e10s', 04:45:04 INFO - 'system_bits': '32', 04:45:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:45:04 INFO - 'title': 'talos-linux64-ix-058', 04:45:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:45:04 INFO - 'use_talos_json': True, 04:45:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:45:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:45:04 INFO - 'work_dir': 'build'} 04:45:04 INFO - [mozharness: 2017-02-16 12:45:04.889834Z] Running clobber step. 04:45:04 INFO - Running pre-action listener: _resource_record_pre_action 04:45:04 INFO - Running main action method: clobber 04:45:04 INFO - rmtree: /builds/slave/test/build 04:45:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:45:06 INFO - Running post-action listener: _resource_record_post_action 04:45:06 INFO - [mozharness: 2017-02-16 12:45:06.342823Z] Finished clobber step (success) 04:45:06 INFO - [mozharness: 2017-02-16 12:45:06.342923Z] Running read-buildbot-config step. 04:45:06 INFO - Running pre-action listener: _resource_record_pre_action 04:45:06 INFO - Running main action method: read_buildbot_config 04:45:06 INFO - Using buildbot properties: 04:45:06 INFO - { 04:45:06 INFO - "project": "", 04:45:06 INFO - "product": "firefox", 04:45:06 INFO - "who": "cbook@mozilla.com", 04:45:06 INFO - "installer_path": "public/build/target.tar.bz2", 04:45:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:45:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:45:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:45:06 INFO - "stage_platform": "linux64", 04:45:06 INFO - "basedir": "/builds/slave/test", 04:45:06 INFO - "buildnumber": 420, 04:45:06 INFO - "platform": "ubuntu64_hw", 04:45:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:45:06 INFO - "slavebuilddir": "test", 04:45:06 INFO - "taskId": "IVqNgSjIRRWqrdZ_KWZOEQ", 04:45:06 INFO - "branch": "mozilla-central", 04:45:06 INFO - "script_repo_revision": "production", 04:45:06 INFO - "revision": "8c8b54b13be7ec12cb8e104b772162a80b524497", 04:45:06 INFO - "slavename": "talos-linux64-ix-058", 04:45:06 INFO - "repo_path": "mozilla-central" 04:45:06 INFO - } 04:45:06 INFO - Finding installer, test and symbols from parent task. 04:45:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVqNgSjIRRWqrdZ_KWZOEQ'}, attempt #1 04:45:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVqNgSjIRRWqrdZ_KWZOEQ'}, attempt #1 04:45:07 INFO - Task dependencies: Qv1FwSjTREKN2nrC3ur4wA 04:45:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA'}, attempt #1 04:45:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 04:45:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/test_packages.json 04:45:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.crashreporter-symbols.zip 04:45:07 INFO - Running post-action listener: _resource_record_post_action 04:45:07 INFO - [mozharness: 2017-02-16 12:45:07.728514Z] Finished read-buildbot-config step (success) 04:45:07 INFO - [mozharness: 2017-02-16 12:45:07.728731Z] Running download-and-extract step. 04:45:07 INFO - Running pre-action listener: _resource_record_pre_action 04:45:07 INFO - Running main action method: download_and_extract 04:45:07 INFO - mkdir: /builds/slave/test/build/tests 04:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:07 INFO - trying https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.test_packages.json 04:45:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:45:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:45:08 INFO - Downloaded 896 bytes. 04:45:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:45:08 INFO - Using the following test package requirements: 04:45:08 INFO - {u'common': [u'target.common.tests.zip'], 04:45:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:45:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:45:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:45:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:45:08 INFO - u'mozbase': [u'target.common.tests.zip'], 04:45:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:45:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:45:08 INFO - u'web-platform': [u'target.common.tests.zip', 04:45:08 INFO - u'target.web-platform.tests.zip'], 04:45:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:45:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:45:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip 04:45:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:45:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip into memory 04:45:09 INFO - Expected file size: 57026161 04:45:09 INFO - Obtained file size: 57026161 04:45:18 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:45:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip 04:45:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:45:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.common.tests.zip into memory 04:45:20 INFO - Expected file size: 57026161 04:45:20 INFO - Obtained file size: 57026161 04:45:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.talos.tests.zip 04:45:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:45:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.talos.tests.zip into memory 04:45:31 INFO - Expected file size: 14403647 04:45:31 INFO - Obtained file size: 14403647 04:45: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')]} 04:45:31 INFO - trying https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 04:45:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:45:35 INFO - Downloaded 56480684 bytes. 04:45:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 04:45:35 INFO - mkdir: /builds/slave/test/properties 04:45:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:35 INFO - Contents: 04:45:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 04:45:35 INFO - Running post-action listener: _resource_record_post_action 04:45:35 INFO - Running post-action listener: set_extra_try_arguments 04:45:35 INFO - [mozharness: 2017-02-16 12:45:35.153024Z] Finished download-and-extract step (success) 04:45:35 INFO - [mozharness: 2017-02-16 12:45:35.153158Z] Running populate-webroot step. 04:45:35 INFO - Running pre-action listener: _resource_record_pre_action 04:45:35 INFO - Running main action method: populate_webroot 04:45:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:35 INFO - '--tppagecycles', 04:45:35 INFO - '7'], 04:45:35 INFO - 'tests': ['tsvgm']}}, 04:45:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['damp', 'tps']}, 04:45:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'tests': ['damp', 'tps']}, 04:45:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['dromaeo_dom']}, 04:45:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['basic_compositor_video']}, 04:45:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:45:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['a11yr', 04:45:35 INFO - 'ts_paint', 04:45:35 INFO - 'tpaint', 04:45:35 INFO - 'sessionrestore', 04:45:35 INFO - 'sessionrestore_no_auto_restore', 04:45:35 INFO - 'tabpaint']}, 04:45:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['tsvgx', 04:45:35 INFO - 'tsvgr_opacity', 04:45:35 INFO - 'tart', 04:45:35 INFO - 'tscrollx', 04:45:35 INFO - 'cart', 04:45:35 INFO - 'tsvg_static']}, 04:45:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:35 INFO - 'tsvgr_opacity', 04:45:35 INFO - 'tart', 04:45:35 INFO - 'tscrollx', 04:45:35 INFO - 'cart', 04:45:35 INFO - 'tsvg_static']}, 04:45:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'talos_options': ['--disable-e10s'], 04:45:35 INFO - 'tests': ['tp5o']}, 04:45:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:45:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'talos_options': ['--disable-e10s', 04:45:35 INFO - '--xperf_path', 04:45:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:35 INFO - 'tests': ['tp5n']}, 04:45:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:45:35 INFO - 'talos_options': ['--xperf_path', 04:45:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:35 INFO - 'tests': ['tp5n']}}, 04:45:35 INFO - 'talos.zip': {'path': '', 04:45:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:35 INFO - Running post-action listener: _resource_record_post_action 04:45:35 INFO - [mozharness: 2017-02-16 12:45:35.170892Z] Finished populate-webroot step (success) 04:45:35 INFO - [mozharness: 2017-02-16 12:45:35.170982Z] Running create-virtualenv step. 04:45:35 INFO - Running pre-action listener: _resource_record_pre_action 04:45:35 INFO - Running main action method: create_virtualenv 04:45:35 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:35 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 04:45:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:35 INFO - Using real prefix '/usr' 04:45:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:37 INFO - Installing pip.................done. 04:45:37 INFO - Return code: 0 04:45:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:45:37 INFO - Reading from file tmpfile_stdout 04:45:37 INFO - Output received: 04:45:37 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) 04:45:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:37 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:37 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 04:45:37 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 04:45:37 INFO - Using env: {'DISPLAY': ':0', 04:45:37 INFO - 'HOME': '/home/cltbld', 04:45:37 INFO - 'LANG': 'en_US.UTF-8', 04:45:37 INFO - 'LANGUAGE': 'en_US:en', 04:45:37 INFO - 'LOGNAME': 'cltbld', 04:45:37 INFO - 'MAIL': '/var/mail/cltbld', 04:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:37 INFO - 'MOZ_NO_REMOTE': '1', 04:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:37 INFO - 'NO_EM_RESTART': '1', 04:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:37 INFO - 'PWD': '/builds/slave/test', 04:45:37 INFO - 'SHELL': '/bin/bash', 04:45:37 INFO - 'SHLVL': '1', 04:45:37 INFO - 'TERM': 'linux', 04:45:37 INFO - 'TMOUT': '86400', 04:45:37 INFO - 'USER': 'cltbld', 04:45:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:37 INFO - '_': '/tools/buildbot/bin/python'} 04:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:38 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 04:45:38 INFO - Cleaning up... 04:45:38 INFO - Return code: 0 04:45:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:38 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:38 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 04:45:38 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 04:45:38 INFO - Using env: {'DISPLAY': ':0', 04:45:38 INFO - 'HOME': '/home/cltbld', 04:45:38 INFO - 'LANG': 'en_US.UTF-8', 04:45:38 INFO - 'LANGUAGE': 'en_US:en', 04:45:38 INFO - 'LOGNAME': 'cltbld', 04:45:38 INFO - 'MAIL': '/var/mail/cltbld', 04:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:38 INFO - 'MOZ_NO_REMOTE': '1', 04:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:38 INFO - 'NO_EM_RESTART': '1', 04:45:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:38 INFO - 'PWD': '/builds/slave/test', 04:45:38 INFO - 'SHELL': '/bin/bash', 04:45:38 INFO - 'SHLVL': '1', 04:45:38 INFO - 'TERM': 'linux', 04:45:38 INFO - 'TMOUT': '86400', 04:45:38 INFO - 'USER': 'cltbld', 04:45:38 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:38 INFO - '_': '/tools/buildbot/bin/python'} 04:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:38 INFO - Downloading/unpacking psutil>=3.1.1 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:40 INFO - Installing collected packages: psutil 04:45:40 INFO - Running setup.py install for psutil 04:45:40 INFO - building 'psutil._psutil_linux' extension 04:45:40 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 04:45:40 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 04:45:40 INFO - building 'psutil._psutil_posix' extension 04:45:40 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 04:45:40 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 04:45:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:41 INFO - Successfully installed psutil 04:45:41 INFO - Cleaning up... 04:45:41 INFO - Return code: 0 04:45:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:45:41 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')]} 04:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:41 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')]} 04:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:41 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:41 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 04:45:41 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 04:45:41 INFO - Using env: {'DISPLAY': ':0', 04:45:41 INFO - 'HOME': '/home/cltbld', 04:45:41 INFO - 'LANG': 'en_US.UTF-8', 04:45:41 INFO - 'LANGUAGE': 'en_US:en', 04:45:41 INFO - 'LOGNAME': 'cltbld', 04:45:41 INFO - 'MAIL': '/var/mail/cltbld', 04:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:41 INFO - 'MOZ_NO_REMOTE': '1', 04:45:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:41 INFO - 'NO_EM_RESTART': '1', 04:45:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:41 INFO - 'PWD': '/builds/slave/test', 04:45:41 INFO - 'SHELL': '/bin/bash', 04:45:41 INFO - 'SHLVL': '1', 04:45:41 INFO - 'TERM': 'linux', 04:45:41 INFO - 'TMOUT': '86400', 04:45:41 INFO - 'USER': 'cltbld', 04:45:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:41 INFO - '_': '/tools/buildbot/bin/python'} 04:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:45:41 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 04:45:41 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 04:45:41 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 04:45:41 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 04:45:41 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 04:45:41 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 04:45:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:45:43 INFO - Installing collected packages: mozsystemmonitor 04:45:43 INFO - Running setup.py install for mozsystemmonitor 04:45:43 INFO - Successfully installed mozsystemmonitor 04:45:43 INFO - Cleaning up... 04:45:43 INFO - Return code: 0 04:45:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:43 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:43 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 04:45:43 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 04:45:43 INFO - Using env: {'DISPLAY': ':0', 04:45:43 INFO - 'HOME': '/home/cltbld', 04:45:43 INFO - 'LANG': 'en_US.UTF-8', 04:45:43 INFO - 'LANGUAGE': 'en_US:en', 04:45:43 INFO - 'LOGNAME': 'cltbld', 04:45:43 INFO - 'MAIL': '/var/mail/cltbld', 04:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:43 INFO - 'MOZ_NO_REMOTE': '1', 04:45:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:43 INFO - 'NO_EM_RESTART': '1', 04:45:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:43 INFO - 'PWD': '/builds/slave/test', 04:45:43 INFO - 'SHELL': '/bin/bash', 04:45:43 INFO - 'SHLVL': '1', 04:45:43 INFO - 'TERM': 'linux', 04:45:43 INFO - 'TMOUT': '86400', 04:45:43 INFO - 'USER': 'cltbld', 04:45:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:43 INFO - '_': '/tools/buildbot/bin/python'} 04:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:43 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:43 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 04:45:43 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 04:45:43 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 04:45:43 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 04:45:43 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 04:45:43 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 04:45:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:45 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 04:45:45 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 04:45:45 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 04:45:45 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 04:45:45 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 04:45:45 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 04:45:46 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:46 INFO - warning: no files found matching '*.txt' 04:45:46 INFO - no previously-included directories found matching 'build' 04:45:46 INFO - no previously-included directories found matching 'dist' 04:45:46 INFO - no previously-included directories found matching '.git*' 04:45:46 INFO - Installing collected packages: jsonschema, functools32 04:45:46 INFO - Running setup.py install for functools32 04:45:46 INFO - warning: no files found matching '*.txt' 04:45:46 INFO - no previously-included directories found matching 'build' 04:45:46 INFO - no previously-included directories found matching 'dist' 04:45:46 INFO - no previously-included directories found matching '.git*' 04:45:46 INFO - Successfully installed jsonschema functools32 04:45:46 INFO - Cleaning up... 04:45:46 INFO - Return code: 0 04:45:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:46 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')]} 04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:46 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')]} 04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:46 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:46 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 04:45:46 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 04:45:46 INFO - Using env: {'DISPLAY': ':0', 04:45:46 INFO - 'HOME': '/home/cltbld', 04:45:46 INFO - 'LANG': 'en_US.UTF-8', 04:45:46 INFO - 'LANGUAGE': 'en_US:en', 04:45:46 INFO - 'LOGNAME': 'cltbld', 04:45:46 INFO - 'MAIL': '/var/mail/cltbld', 04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:46 INFO - 'MOZ_NO_REMOTE': '1', 04:45:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:46 INFO - 'NO_EM_RESTART': '1', 04:45:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:46 INFO - 'PWD': '/builds/slave/test', 04:45:46 INFO - 'SHELL': '/bin/bash', 04:45:46 INFO - 'SHLVL': '1', 04:45:46 INFO - 'TERM': 'linux', 04:45:46 INFO - 'TMOUT': '86400', 04:45:46 INFO - 'USER': 'cltbld', 04:45:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:46 INFO - '_': '/tools/buildbot/bin/python'} 04:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:46 INFO - Cleaning up... 04:45:46 INFO - Return code: 0 04:45:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:46 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')]} 04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:46 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')]} 04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:46 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:46 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 04:45:46 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 04:45:46 INFO - Using env: {'DISPLAY': ':0', 04:45:46 INFO - 'HOME': '/home/cltbld', 04:45:46 INFO - 'LANG': 'en_US.UTF-8', 04:45:46 INFO - 'LANGUAGE': 'en_US:en', 04:45:46 INFO - 'LOGNAME': 'cltbld', 04:45:46 INFO - 'MAIL': '/var/mail/cltbld', 04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:46 INFO - 'MOZ_NO_REMOTE': '1', 04:45:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:46 INFO - 'NO_EM_RESTART': '1', 04:45:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:46 INFO - 'PWD': '/builds/slave/test', 04:45:46 INFO - 'SHELL': '/bin/bash', 04:45:46 INFO - 'SHLVL': '1', 04:45:46 INFO - 'TERM': 'linux', 04:45:46 INFO - 'TMOUT': '86400', 04:45:46 INFO - 'USER': 'cltbld', 04:45:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:46 INFO - '_': '/tools/buildbot/bin/python'} 04:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:46 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 04:45:46 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 04:45:46 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 04:45:46 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 04:45:46 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 04:45:46 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 04:45:48 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 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 04:45:49 INFO - Downloading docopt-0.6.1.tar.gz 04:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:49 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:49 INFO - Running setup.py install for blobuploader 04:45:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:49 INFO - Running setup.py install for requests 04:45:50 INFO - Running setup.py install for docopt 04:45:50 INFO - Successfully installed blobuploader requests docopt 04:45:50 INFO - Cleaning up... 04:45:50 INFO - Return code: 0 04:45:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:50 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:50 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 04:45:50 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 04:45:50 INFO - Using env: {'DISPLAY': ':0', 04:45:50 INFO - 'HOME': '/home/cltbld', 04:45:50 INFO - 'LANG': 'en_US.UTF-8', 04:45:50 INFO - 'LANGUAGE': 'en_US:en', 04:45:50 INFO - 'LOGNAME': 'cltbld', 04:45:50 INFO - 'MAIL': '/var/mail/cltbld', 04:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:50 INFO - 'MOZ_NO_REMOTE': '1', 04:45:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:50 INFO - 'NO_EM_RESTART': '1', 04:45:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:50 INFO - 'PWD': '/builds/slave/test', 04:45:50 INFO - 'SHELL': '/bin/bash', 04:45:50 INFO - 'SHLVL': '1', 04:45:50 INFO - 'TERM': 'linux', 04:45:50 INFO - 'TMOUT': '86400', 04:45:50 INFO - 'USER': 'cltbld', 04:45:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:50 INFO - '_': '/tools/buildbot/bin/python'} 04:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:50 INFO - Running setup.py (path:/tmp/pip-EQ6rvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:50 INFO - Running setup.py (path:/tmp/pip-Icnxiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:50 INFO - Running setup.py (path:/tmp/pip-zQVYYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:50 INFO - Running setup.py (path:/tmp/pip-efXFwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:50 INFO - Running setup.py (path:/tmp/pip-kNqo0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:50 INFO - Running setup.py (path:/tmp/pip-PmLZRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:51 INFO - Running setup.py (path:/tmp/pip-BRUPxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:51 INFO - Running setup.py (path:/tmp/pip-AknXUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:51 INFO - Running setup.py (path:/tmp/pip-4z6dr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:51 INFO - Running setup.py (path:/tmp/pip-CyTu38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:51 INFO - Running setup.py (path:/tmp/pip-XsNgXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:51 INFO - Running setup.py (path:/tmp/pip-3UuLWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:51 INFO - Running setup.py (path:/tmp/pip-L3BiQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:51 INFO - Running setup.py (path:/tmp/pip-C0u2cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:51 INFO - Running setup.py (path:/tmp/pip-6LyX0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:51 INFO - Running setup.py (path:/tmp/pip-ChQ9mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:51 INFO - Running setup.py (path:/tmp/pip-tOw9LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:51 INFO - Running setup.py install for manifestparser 04:45:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:51 INFO - Running setup.py install for mozcrash 04:45:52 INFO - Running setup.py install for mozdebug 04:45:52 INFO - Running setup.py install for mozdevice 04:45:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Running setup.py install for mozfile 04:45:52 INFO - Running setup.py install for mozhttpd 04:45:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Running setup.py install for mozinfo 04:45:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Running setup.py install for mozInstall 04:45:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Running setup.py install for mozleak 04:45:52 INFO - Running setup.py install for mozlog 04:45:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:52 INFO - Running setup.py install for moznetwork 04:45:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Running setup.py install for mozprocess 04:45:53 INFO - Running setup.py install for mozprofile 04:45:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Running setup.py install for mozrunner 04:45:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Running setup.py install for mozscreenshot 04:45:53 INFO - Running setup.py install for moztest 04:45:53 INFO - Running setup.py install for mozversion 04:45:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:53 INFO - Cleaning up... 04:45:53 INFO - Return code: 0 04:45:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:53 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:53 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 04:45:53 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 04:45:53 INFO - Using env: {'DISPLAY': ':0', 04:45:53 INFO - 'HOME': '/home/cltbld', 04:45:53 INFO - 'LANG': 'en_US.UTF-8', 04:45:53 INFO - 'LANGUAGE': 'en_US:en', 04:45:53 INFO - 'LOGNAME': 'cltbld', 04:45:53 INFO - 'MAIL': '/var/mail/cltbld', 04:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:53 INFO - 'MOZ_NO_REMOTE': '1', 04:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:53 INFO - 'NO_EM_RESTART': '1', 04:45:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:53 INFO - 'PWD': '/builds/slave/test', 04:45:53 INFO - 'SHELL': '/bin/bash', 04:45:53 INFO - 'SHLVL': '1', 04:45:53 INFO - 'TERM': 'linux', 04:45:53 INFO - 'TMOUT': '86400', 04:45:53 INFO - 'USER': 'cltbld', 04:45:53 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:53 INFO - '_': '/tools/buildbot/bin/python'} 04:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:53 INFO - Running setup.py (path:/tmp/pip-sZ0qIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:53 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)) 04:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:53 INFO - Running setup.py (path:/tmp/pip-GXvPsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:54 INFO - Running setup.py (path:/tmp/pip-i596iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:54 INFO - Running setup.py (path:/tmp/pip-KQUpK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:54 INFO - Running setup.py (path:/tmp/pip-HtZuJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:54 INFO - Running setup.py (path:/tmp/pip-JnH8tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:54 INFO - Running setup.py (path:/tmp/pip-sNw5FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:54 INFO - Running setup.py (path:/tmp/pip-joM142-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:54 INFO - Running setup.py (path:/tmp/pip-0rU4Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:54 INFO - Running setup.py (path:/tmp/pip-1chmez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:54 INFO - Running setup.py (path:/tmp/pip-KaKRZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:54 INFO - Running setup.py (path:/tmp/pip-r5YgPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:54 INFO - Running setup.py (path:/tmp/pip-hu8Jgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:54 INFO - Running setup.py (path:/tmp/pip-D2PhEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:54 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)) 04:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:54 INFO - Running setup.py (path:/tmp/pip-BfJ12U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:55 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)) 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:55 INFO - Running setup.py (path:/tmp/pip-idTOT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:55 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)) 04:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:55 INFO - Running setup.py (path:/tmp/pip-sBiwZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:55 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)) 04:45:55 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)) 04:45:55 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)) 04:45:55 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)) 04:45:55 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)) 04:45:55 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)) 04:45:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:57 INFO - Downloading blessings-1.6.tar.gz 04:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:57 INFO - Installing collected packages: blessings 04:45:57 INFO - Running setup.py install for blessings 04:45:57 INFO - Successfully installed blessings 04:45:57 INFO - Cleaning up... 04:45:57 INFO - Return code: 0 04:45:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:57 INFO - Reading from file tmpfile_stdout 04:45:57 INFO - Current package versions: 04:45:57 INFO - argparse == 1.2.1 04:45:57 INFO - blessings == 1.6 04:45:57 INFO - blobuploader == 1.2.4 04:45:57 INFO - docopt == 0.6.1 04:45:57 INFO - functools32 == 3.2.3-2 04:45:57 INFO - jsonschema == 2.5.1 04:45:57 INFO - manifestparser == 1.1 04:45:57 INFO - mozInstall == 1.12 04:45:57 INFO - mozcrash == 1.0 04:45:57 INFO - mozdebug == 0.1 04:45:57 INFO - mozdevice == 0.49 04:45:57 INFO - mozfile == 1.2 04:45:57 INFO - mozhttpd == 0.7 04:45:57 INFO - mozinfo == 0.9 04:45:57 INFO - mozleak == 0.1 04:45:57 INFO - mozlog == 3.4 04:45:57 INFO - moznetwork == 0.27 04:45:57 INFO - mozprocess == 0.24 04:45:57 INFO - mozprofile == 0.28 04:45:57 INFO - mozrunner == 6.13 04:45:57 INFO - mozscreenshot == 0.1 04:45:57 INFO - mozsystemmonitor == 0.3 04:45:57 INFO - moztest == 0.8 04:45:57 INFO - mozversion == 1.4 04:45:57 INFO - psutil == 3.1.1 04:45:57 INFO - requests == 1.2.3 04:45:57 INFO - wsgiref == 0.1.2 04:45:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 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 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:57 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 04:45:57 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 04:45:57 INFO - Using env: {'DISPLAY': ':0', 04:45:57 INFO - 'HOME': '/home/cltbld', 04:45:57 INFO - 'LANG': 'en_US.UTF-8', 04:45:57 INFO - 'LANGUAGE': 'en_US:en', 04:45:57 INFO - 'LOGNAME': 'cltbld', 04:45:57 INFO - 'MAIL': '/var/mail/cltbld', 04:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:57 INFO - 'MOZ_NO_REMOTE': '1', 04:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:57 INFO - 'NO_EM_RESTART': '1', 04:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:57 INFO - 'PWD': '/builds/slave/test', 04:45:57 INFO - 'SHELL': '/bin/bash', 04:45:57 INFO - 'SHLVL': '1', 04:45:57 INFO - 'TERM': 'linux', 04:45:57 INFO - 'TMOUT': '86400', 04:45:57 INFO - 'USER': 'cltbld', 04:45:57 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:57 INFO - '_': '/tools/buildbot/bin/python'} 04:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 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)) 04:45:57 INFO - Cleaning up... 04:45:57 INFO - Return code: 0 04:45:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, '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': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', '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 04:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:45:57 INFO - Using env: {'DISPLAY': ':0', 04:45:57 INFO - 'HOME': '/home/cltbld', 04:45:57 INFO - 'LANG': 'en_US.UTF-8', 04:45:57 INFO - 'LANGUAGE': 'en_US:en', 04:45:57 INFO - 'LOGNAME': 'cltbld', 04:45:57 INFO - 'MAIL': '/var/mail/cltbld', 04:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:57 INFO - 'MOZ_NO_REMOTE': '1', 04:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:45:57 INFO - 'NO_EM_RESTART': '1', 04:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:57 INFO - 'PWD': '/builds/slave/test', 04:45:57 INFO - 'SHELL': '/bin/bash', 04:45:57 INFO - 'SHLVL': '1', 04:45:57 INFO - 'TERM': 'linux', 04:45:57 INFO - 'TMOUT': '86400', 04:45:57 INFO - 'USER': 'cltbld', 04:45:57 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:57 INFO - '_': '/tools/buildbot/bin/python'} 04:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:58 INFO - Cleaning up... 04:45:58 INFO - Return code: 0 04:45:58 INFO - Running post-action listener: _resource_record_post_action 04:45:58 INFO - Running post-action listener: _start_resource_monitoring 04:45:58 INFO - Starting resource monitoring. 04:45:58 INFO - [mozharness: 2017-02-16 12:45:58.129341Z] Finished create-virtualenv step (success) 04:45:58 INFO - [mozharness: 2017-02-16 12:45:58.129850Z] Running install step. 04:45:58 INFO - Running pre-action listener: _resource_record_pre_action 04:45:58 INFO - Running main action method: install 04:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:58 INFO - Reading from file tmpfile_stdout 04:45:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:58 INFO - Reading from file tmpfile_stdout 04:45:58 INFO - Output received: 04:45:58 INFO - Usage: mozinstall [options] installer 04:45:58 INFO - Options: 04:45:58 INFO - -h, --help show this help message and exit 04:45:58 INFO - -d DEST, --destination=DEST 04:45:58 INFO - Directory to install application into. [default: 04:45:58 INFO - "/builds/slave/test"] 04:45:58 INFO - --app=APP Application being installed. [default: firefox] 04:45:58 INFO - mkdir: /builds/slave/test/build/application 04:45:58 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'] 04:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:46:14 INFO - Reading from file tmpfile_stdout 04:46:14 INFO - Output received: 04:46:14 INFO - /builds/slave/test/build/application/firefox/firefox 04:46:14 INFO - Running post-action listener: _resource_record_post_action 04:46:14 INFO - [mozharness: 2017-02-16 12:46:14.760257Z] Finished install step (success) 04:46:14 INFO - [mozharness: 2017-02-16 12:46:14.760536Z] Running run-tests step. 04:46:14 INFO - Running pre-action listener: _resource_record_pre_action 04:46:14 INFO - Running main action method: run_tests 04:46:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:14 INFO - Python 2.7.3 04:46:14 INFO - Return code: 0 04:46:14 INFO - grabbing minidump binary from tooltool 04:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:14 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 0x1cb0290>, '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 0x1cb0b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:14 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 04:46:14 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 04:46:14 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 04:46:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:14 INFO - Return code: 0 04:46:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:46:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:14 INFO - ENV: RUST_BACKTRACE is now 1 04:46:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:46:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:14 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/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 04:46:14 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/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 04:46:14 INFO - Using env: {'DISPLAY': ':0', 04:46:14 INFO - 'HOME': '/home/cltbld', 04:46:14 INFO - 'LANG': 'en_US.UTF-8', 04:46:14 INFO - 'LANGUAGE': 'en_US:en', 04:46:14 INFO - 'LOGNAME': 'cltbld', 04:46:14 INFO - 'MAIL': '/var/mail/cltbld', 04:46:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:14 INFO - 'MOZ_NO_REMOTE': '1', 04:46:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:14 INFO - 'NO_EM_RESTART': '1', 04:46:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:14 INFO - 'PWD': '/builds/slave/test', 04:46:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:14 INFO - 'RUST_BACKTRACE': '1', 04:46:14 INFO - 'SHELL': '/bin/bash', 04:46:14 INFO - 'SHLVL': '1', 04:46:14 INFO - 'TERM': 'linux', 04:46:14 INFO - 'TMOUT': '86400', 04:46:14 INFO - 'USER': 'cltbld', 04:46:14 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091', 04:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:14 INFO - '_': '/tools/buildbot/bin/python'} 04:46:14 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/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 04:46:15 INFO - mozversion application_buildid: 20170216121511 04:46:15 INFO - mozversion application_changeset: 8c8b54b13be7ec12cb8e104b772162a80b524497 04:46:15 INFO - mozversion application_display_name: Nightly 04:46:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:15 INFO - mozversion application_name: Firefox 04:46:15 INFO - mozversion application_remotingname: firefox 04:46:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:15 INFO - mozversion application_vendor: Mozilla 04:46:15 INFO - mozversion application_version: 54.0a1 04:46:15 INFO - mozversion platform_buildid: 20170216121511 04:46:15 INFO - mozversion platform_changeset: 8c8b54b13be7ec12cb8e104b772162a80b524497 04:46:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:15 INFO - mozversion platform_version: 54.0a1 04:46:15 INFO - using testdate: 1487249175 04:46:15 INFO - actual date: 1487249175 04:46:15 INFO - starting webserver on 'localhost:60683' 04:46:15 INFO - SUITE-START | Running 2 tests 04:46:15 INFO - TEST-START | dromaeo_css 04:46:15 INFO - operating with platform_type : linux_ 04:46:15 INFO - Initialising browser for dromaeo_css test... 04:46:15 INFO - TEST-INFO | started process 31360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJEicSB/profile http://localhost:60683/getInfo.html) 04:46:18 INFO - PROCESS | 31360 | Iterating over profile /tmp/tmpJEicSB/profile 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/pluginreg.dat = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/prefs.js = Thu Feb 16 2017 04:45:31 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/cache2 = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/places.sqlite-wal = Thu Feb 16 2017 04:46:18 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/places.sqlite = Thu Feb 16 2017 04:46:18 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/safebrowsing = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/directoryLinks.json = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:18 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:18 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/secmod.db = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/localstore.rdf = Thu Feb 16 2017 04:45:31 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | Stat failure 04:46:19 INFO - PROCESS | 31360 | __metrics Screen width/height:1600/1200 04:46:19 INFO - PROCESS | 31360 | colorDepth:24 04:46:19 INFO - PROCESS | 31360 | Browser inner width/height: 1024/697 04:46:19 INFO - PROCESS | 31360 | __metrics 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/frequencyCap.json = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/.parentlock = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/thumbnails = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/extensions.json = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/sessionCheckpoints.json = Thu Feb 16 2017 04:46:18 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/startupCache = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/extensions.ini = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/crashes = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/bookmarkbackups = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/minidumps = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/user.js = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/blocklist.xml = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/gmp = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/extensions = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/key3.db = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/content-prefs.sqlite = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/addons.json = Thu Feb 16 2017 04:46:17 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/places.sqlite-shm = Thu Feb 16 2017 04:46:16 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/permissions.sqlite = Thu Feb 16 2017 04:46:16 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/cert8.db = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:19 INFO - PROCESS | 31360 | No birth date. Using mtime. 04:46:19 INFO - PROCESS | 31360 | Using date: /tmp/tmpJEicSB/profile/compatibility.ini = Thu Feb 16 2017 04:46:15 GMT-0800 (PST) 04:46:20 ERROR - PROCESS | 31360 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:46:21 INFO - TEST-INFO | 31360: exit 0 04:46:21 INFO - Browser initialized. 04:46:21 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:21 INFO - TEST-INFO | started process 31478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJEicSB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:33 INFO - PROCESS | 31478 | RSS: Main: 160260096 04:46:33 INFO - PROCESS | 31478 | 04:49:37 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60683/tests/dromaeo/cssquery-ext.html) 04:49:38 INFO - PROCESS | 31478 | RSS: Main: 172834816 04:49:38 INFO - PROCESS | 31478 | 04:51:21 INFO - PROCESS | 31478 | ************************* 04:51:21 INFO - PROCESS | 31478 | A coding exception was thrown and uncaught in a Task. 04:51:21 INFO - PROCESS | 31478 | 04:51:21 ERROR - PROCESS | 31478 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:51:21 INFO - PROCESS | 31478 | Full stack: 04:51:21 INFO - PROCESS | 31478 | ************************* 04:52:41 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-ext.html (next: http://localhost:60683/tests/dromaeo/cssquery-jquery.html) 04:52:42 INFO - PROCESS | 31478 | RSS: Main: 166113280 04:52:42 INFO - PROCESS | 31478 | 04:55:43 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60683/tests/dromaeo/cssquery-mootools.html) 04:55:44 INFO - PROCESS | 31478 | RSS: Main: 173445120 04:55:44 INFO - PROCESS | 31478 | 04:58:46 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60683/tests/dromaeo/cssquery-prototype.html) 04:58:47 INFO - PROCESS | 31478 | RSS: Main: 166752256 04:58:47 INFO - PROCESS | 31478 | 05:01:49 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60683/tests/dromaeo/cssquery-yui.html) 05:01:50 INFO - PROCESS | 31478 | RSS: Main: 170213376 05:01:50 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | Cycle 1(1): loaded http://localhost:60683/tests/dromaeo/cssquery-yui.html (next: http://localhost:60683/tests/dromaeo/cssquery-dojo.html) 05:04:53 INFO - PROCESS | 31478 | RSS: Main: 170754048 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | __start_tp_report 05:04:53 INFO - PROCESS | 31478 | _x_x_mozilla_page_load 05:04:53 INFO - PROCESS | 31478 | _x_x_mozilla_page_load_details 05:04:53 INFO - PROCESS | 31478 | |i|pagename|runs| 05:04:53 INFO - PROCESS | 31478 | |0;dojo.html;2518.8492898409368;2526.052730226165;2547.694276686798;2549.859757713326;2570.4216551275954;4596.287575425806;4605.355250264962;4625.629949604032;4636.884077898054;4714.717116972984;4664.696794708341;4723.315119307698;4759.90480190396;4779.856604301868;4846.030793841228;4115.526714427834;4373.322135069061;4412.8455504057365;4414.29371300592;4434.490033646123;4357.520672725997;4383.320585309282;4384.473863136413;4393.231184542708;4393.956060439403;4174.749515029078;4268.487781466244;4291.914161716748;4384.276594361926;4395.669818715808;4293.635041021517;4301.806418711166;4307.267764480014;4336.718113322624;4348.543402942709;4192.413062171298;4214.978925105354;4215.135897141079;4271.337942618899;4308.67684923632;4576;4611.700239484423;4615.123126605934;4647.326137710047;4657.69724967876;5038.5465308122075;5039.546440820307;5042.899142017176;5085.059264036166;5115.386153661547;5079.568236699885;5081.237814327844;5087.414947281073;5103.464136265676;5130.256112863613;4613.607843333287;4646.651501137429;4676.3453116563705;4768.284757286435;4780.528911691066;5059;5124.12889808726;5167.974160129175;5224.791008359699;5236.659617124875;4841.225403935424;4918.065567542156;4944.703317800943;4971.005798840174;4973.651844370932;5017.247412888096;5059.241113832954;5072.82245121419;5074.112030394666;5093.159628661304;4842.491538388418;4886.291487734258;4911.4106307243355;5035.446100928895;5037.622178336639;4923.163062279421;4932.802687892444;4965.081459929925;4994.375703037121;4995.275685025651;4260.531341552427;4274.487061552633;4316.7409955402145;4320.9629688875075;4325.199838029975;3638.21778317663;3668.944965825515;3685.1155722626377;3689;3691.1325838427947;4299.97850010748;4346.826126954887;4367.759773212504;4368.694191406635;4399.956000439955;4917.188663870493;4943.975280123648;5030.572401345853;5039.949600503948;5083.517065878736;4341.782910854445;4344.261475549164;4345.978270108692;4365.236083685342;4421.53573874745;4486;4611.700239484423;4613.754286342669;4616.815327386934;4625.259958406638;4757.833475828329;4783.521647835189;4800.66395352322;4804.327394164819;4827.275908613736;4889.315495830516;4911.214205727067;5039.395272567315;5049.444561098203;5068.771905264346;4789.066132104206;4794.184988552024;4795.904081918412;4829.420469543677;4835.758212089311;4992.450830408725;4998.550130488274;5012.04771093491;5021.974890125526;5066.594672426123;4877.5366340197625;4893.90212195747;4918;4927.605791536596;5102.897942041212;4808.519148085164;4815.109204797123;4853.272009198648;4858.076965376567;4886.242632391985;4770.522947705202;4820.25286080658;4824.734639594715;4854.077725232195;4866.026794641157;4846.80612775485;4904.950950490449;4910.091633047898;4924.236743304794;4958.1323268428605;4761.857144285677;4782.354382158364;4831.95168048315;4929.334539837219;4988.975055124701;4849.515048495264;4852.927206091982;4860.562549370574;4898.191798353232;4905.852824415273 05:04:53 INFO - PROCESS | 31478 | |1;ext.html;508.1209507551935;509.8317555206782;523.5026724611618;549.2530158983783;570.5293133165135;37342.43986340204;39339.40990885139;39387.21225575494;39467.210655786905;39468.80265598668;39081.218375632474;39987.60012399875;40024.19951600966;40064.59935400645;40117.398239026435;7397.150712321906;7632.6183690815515;7654.043244594426;7665.616719164047;9485.146336829685;9400.388974716656;9482.952585237064;9492.762680932963;9497.430154190735;9502.144806967372;9514.191293740041;9548.37935534188;9551.665691700791;9557.092076252746;9568.617255309779;9393.953030234874;9401.153896149344;9453.52732363379;9460.24318054554;9461.432314061178;9316.906830931672;9323.487208203545;9355.906440935572;9388.953055234748;9405;32692;32995.6700432995;33131.66868331334;33193.17017074568;33235;3277.041810876387;3329.8501567429525;3386.1703882548686;3393.575803024622;3414.3854106260865;25553.744462555507;26120;26120;26192;26259.73740262592;3301;3346.966530334714;3405.9659403406145;3555.7866528008144;3560.4659301104784;227509;228607;229027;231113;231259;38430.27063782136;39386.409203861964;47941.28088078681;47972;48073;53524.732376337866;54826.72586637041;54890.17664735032;54988;55025.72487137539;12355.69110772238;12473.75052498945;12506.624801255977;12556.874431255754;12628.684282893002;9584.616615335448;9626.229901607854;9687.66093186735;9703.07820757027;9749.610015599439;15869.285882135331;15916.681666366609;15950.441734539236;15962.521124366056;15973.600659983362;6474.417302442803;6542.411182993554;6583.539152259392;6583.769568065095;6617.702203400859;744.1999850161098;745.1505283976271;750.7147284032033;762.6453699029978;765.4221063097326;25932;26866.05968791083;27137.321566960985;27267.590986135223;27320.726792731817;932.5570354081757;937.1144268666045;941.8304705153138;947.2706016367496;1013.2704452793977;14302.928485357506;14413.30797150303;14546.272686365639;14597.12417254947;14792.408303667948;325042;325309;327266;327298;330984;48879.26681099786;49585.75207124013;49858.50141498539;49906.50093499091;49925.750371248636;1143.3997151495364;1174.7826652069223;1177.2936238256978;1178.6935396796723;1186.5846953566356;24817.759112044685;25713.35716607025;26368.73631263663;26718.931242750074;26736.331591709586;41205.1758964825;41249.1750164989;41303.1739365217;41317.58682413137;41462;274810;275760;276116.4854393201;276263;277306;22688.20591279297;23901.163459278843;23924.401889952194;24933.376665583506;25065;35790.28419431649;35844.64155358413;35873.103172420895;36276.81861590675;36667.26665466622;30713.232169195948;31380;31664;31735.682643173273;32447.513287301183;30753;30916.53625195579;30974.606142724082;31181.376372472878;31326.843365783025;31274.53088203724;31507.84246078755;31679.36641267208;31740.682593173773;31939;26878.193654190407;28438;28551.857240713663;28599.42801144007;28647.856760716895;21970.45073873166;22115.115395384648;22334.441638958506;22503.662445063666;22571.548569028855 05:04:53 INFO - PROCESS | 31478 | |2;jquery.html;392.7407910778887;395.6182284095847;399.4028926754502;403.9798010099495;417.3975264227541;16966.321347146113;17091.145442727844;17559;17666.470005899828;17689.734653980184;17316.307347706075;17405.738913916302;17557.12214389282;17826;18016.729749053775;4883.243097319921;4911.45973942866;5073.695578265298;5086.643934924538;5089.414717307502;5035.496450354972;5162.380514338284;5182.378114626231;5188.688678679291;5194.792208311682;5150.768215430315;5153.768080436389;5171.689698618095;5175.430702622709;5178.300929374543;5400.729963501809;5649.350324712649;5691.08942569189;5723.7996670116345;5749.82750517481;5450.400455949843;5562.4993750562235;5589.385167631557;5590.133529302964;5616.606837521375;11043.337399756041;11160.888391116066;11179.49692263842;11226.775464490664;11274.32354058751;2384.511175209078;2411.9758802412102;2426.2842461473733;2433.318670772169;2454.4088824331243;9570.186534144606;9640.517974101267;10140.492975351277;10183.59265629374;12187.93906030473;1723.8534724548429;1740.7040803063383;1755.8858674186265;1759.1643969114696;1789.5168304557826;70741.2925870745;73871.26128738698;76276.23723762803;77547.61226193889;77969;10613.620229370132;10856.457177141112;10861.40262285582;10922.890771092187;10962.397068161328;15242;15292.617684557752;15300.234988250542;15320.863366890877;15345.539633811004;6692.263850976388;7064.540804847668;7097.751578694721;7152.9284707152265;7220.638968051681;6324.873502529924;6362;6401.711922963478;6493.285738568754;6542.934570654328;4583.2666773316105;4691.695039822469;4709.693869898446;4718.811247550128;4813.15769740301;1955.3058664174298;1956.1686283329811;1961.1665042356942;1972.9714887647392;2004.1499571038123;856.905740368559;885.7564169853342;887.5917078143997;888.3958907942532;912.7946212102356;15383.769243461356;16392.590185245463;16490.17549122539;16755.497335079966;17623.647527049387;866.5147517390282;871.5685735560938;879.5909901895649;880.4717169698258;883.8630012348096;9207.26341892647;9259.583318750672;9461.148496635336;9541.52292385378;9631.71104866855;485354;487275;493132;497529;501749;56209.71895140498;73182;74078;80917;82092;880.5421180985979;912.9041450647719;932.2215949682092;943.4622265308708;945.2532499325594;14383.568492945229;14493.565193044225;14631.341589628706;14854;15248.847511524742;37255.627443725214;37263.25473490569;37841.4323785138;37928;38165.809170953966;50742.492575073775;53090.20364694455;53201.20198197108;53840.730796345764;54074.72962635162;7657.57883316423;7719.961400192963;7732.729354472566;7776.533407995538;8155.062167850536;8938.927328720596;11478.483468244116;11979.580714674945;12012.939935300268;12074.818877717016;8855.690050848189;9139.67474716149;9171;9290.349675522648;9295.72112836616;8828.911710882809;10107.898921010696;10281.897181028095;10378.325408848401;10706.678799635712;5571.860703482445;7223.747168849065;8132.861399404206;9687.41875487473;9959.900400995897;7726.218790116447;7886;7964.402669799672;8070.394720395876;8172.42793004484;9288.674896378594;9353.672621458216;9398.34211605181;9434.716958491255;9760.951195243979 05:04:53 INFO - PROCESS | 31478 | |3;mootools.html;1344.7243315120397;1369.6370461827617;1374.1823614949108;1376.291210026837;1381.9861801381987;7175.856482870339;7520.473566850323;7581.848363032736;7590.848183036343;7721.9227807722045;7748.96125519373;7850.2542258485355;7874.251946065115;7882.763517094496;7909.169537198583;2095.2561840546614;2132.4882028313173;2147.1196809308185;2150.6451435513122;2155.622766015949;2067.762207346152;2100.7584127825266;2104.8105670489654;2106.9367918962457;2117.756458007326;2087.279888438486;2101.254054810543;2113.0913707106015;2114.8202402795705;2127.393692797553;2051.6717325227964;2065.5042789730505;2066.0496171761006;2069.6481598128203;2091.466675997626;1800.7659004329485;1851.7037274036225;2044.9979510039973;2123.893805309728;2155.708979287792;7548.849023019564;7791.532508049535;7813.101493328283;7952.761417157494;7955;4565.794539245742;4696.718196908197;4702.8118875244945;4711.363965864599;4712.717236965793;2928.5314349703945;2995;3029.59100521429;3044.360684256309;3050.771192160597;1147.713071732067;1166.4109624639589;1172.929624222541;1176.9176157668967;1206.0291465370374;10253.897461025368;10270;10344.327618704836;10347.689569312932;10368.63709770162;3830.616938306203;4009;4046.736962097454;4058.512978442606;4086.4687590613626;8324.125966773523;8355.874661880076;8396;8406;8409.537475438787;4590.036092420562;4592.908141837144;4593.655475839326;4596.241620132708;4597.908041839144;3970.2456533259165;4018.799060046986;4116.469842276291;4131.338985762263;4173.603507666767;3332.9333413331597;3363.613184483767;3428.5200071989943;3429.9657003430148;3454.809985450824;1003.9648612298535;1031.582209205273;1047.324475713176;1049.6693541534391;1056.7569459024467;760.2435576601317;787.9645415956296;793.5714714054346;793.9444238903337;803.4295650088503;4578.839740609063;4636.907261854744;4706.647001474903;4804.8318308859725;4875.634327425457;503.68771361755944;510.49913255030094;517.5238780322113;518.9143791274397;529.8463445600732;2972.7473164781236;3002.7297543221216;3143.6227652681823;3341.966580334214;3415.316936612687;8329.625166867505;8362.749117526484;8593.097724738938;8655.74032779005;9287.907120928705;8898.510581917928;8902;8933.910660893438;8934.151255630704;8946;7007.509474336747;7228.241034691387;7337.3029562191505;7352.448566357544;7353.52202106872;5019.534736132361;5062.507797878977;5174.353205849269;5265.236540701653;5294.470552944675;8631.482111073356;8675.869861952202;8712.21590056898;8750.168733970262;8798;8422.24199822019;8655.91344086551;8737.868931966153;8763.123687631185;8792.29661627081;6627.668616569056;6696.1629796275465;6705.899411508929;6756.560823546453;6790.524663273523;7183.5330703504105;7206.207317195209;7312.853742925218;7320.15818180916;7339.853202935805;6453.515986300952;6524.510661700297;6534.346565343428;6649.46804255668;6746.5277430581355;6419;6428.453581445629;6447.9032814508755;6555.410013098844;6576.704048317742;5422.48486393792;5493.148561972901;5498.340199176125;5503.009458297384;5507.366652834975;4109.589041095986;4177.143685544517;4256.680748943903;4261.041265715189;4323.956760432481;4192.664586833107;4276.679249056271;4355.194289056535;4356.302991521468;4372.169287835428 05:04:53 INFO - PROCESS | 31478 | |4;prototype.html;356.8776198854604;359.27068051854735;361.85887503873477;362.92378600493885;366.5161986178244;4832.927506087407;4865.68373055752;4903.485134060926;4913.213885778272;4914.189158788796;4811.326414302001;4852.781624826883;4873.853784386465;4885.706857588547;4891.584215341692;3649.1606930405956;3787.450819631166;3826.1773718650484;3826.9425958610504;3840.1743625120594;3795.867144649938;3799.6960243180615;3808.733388662795;3814.3788889721895;3823;3791.526059242595;3798.2973149967343;3803.695704343646;3808.5048943637426;3834.4440056191834;3715.7180772633396;3801.505804245458;3819.3888977763563;3841.78870162141;3855.306044911915;3322.6843449872467;3325.96674033259;3341.62993172798;3341.983290083558;3349.832508374596;4710.222813235812;4728.810847566093;4729.503402142795;4735.266033764772;4779.880502987418;4313.482382114135;4337.392765012901;4347.2826983547675;4356.978215108936;4362.214801335758;4982.60139188864;4994.800207991676;5012.398512178525;5051.974740126313;5078.898422031576;3620.945685814715;3641.2171383152486;3651.7808931463933;3655.798931058791;3661.8718344857803;5111.156659151273;5204.661696989683;5377.677339359651;5395.974764794676;5417.864553386157;3620.837062332201;3698.9445158322646;3709.2025214578994;3712.3317802795755;3725.5097960815347;3750.6811920986474;3765.1528406108405;3776.4712940188397;3777.962220377816;3788.924221515554;4291;4319.6976211665515;4330.2638551446325;4337.869863904088;4346.695731298842;5033.597312215014;5101.362329708786;5125.8718532036255;5133.845984620468;5174.7153906535505;1265.8860203021281;1278;1278.3927634373783;1279.8720127987312;1287.311288460669;827.2802661684373;867.2914358713325;872.2629378175432;874.7638137702785;875.7329014650466;4328.343313373253;4355.216061109031;4360.7601581913295;4361.563843615613;4364.607185353333;4989.825356112518;5113.616478764107;5159.58723302135;5166.302549155891;5189.662671926389;4197.580241975778;4351.390805287262;4366.694331396771;4391.494978077498;4410.80151393188;2720.408560991842;2729.9317517061836;2733.316670832292;2737.5209338365707;2749.2164733050986;4808.855734327975;4809.759512024385;4831.777382404063;4848.812041049965;4863.392075990501;4984.77568509418;5098.490150984871;5172.922406163911;5186.507281808223;5196.53231209193;4745.2882067690125;4767.73777442237;4771.498992605796;4776.307435421914;4790.9281360780315;5075.720835354091;5077.746112694276;5085;5106.183010718267;5125.923111153261;4768.876385967197;5036.874078148076;5042.621803364689;5043.218301163325;5067.822626208065;4781.760911954458;4782.952170478251;4788.324086569812;4802.494179249098;4936.198287625063;3717.1078941054493;3735.140917589024;3739.8691045813266;3742.6631603154756;3755.2114056047444;5214.32213812202;5226.320578324793;5251.238570407346;5265.552428043659;5278.736063196748;4932.692836398429;5047.697138171721;5103.8468845934685;5109.693418394908;5150.913879878655;5077.806715421799;5085.338905942203;5087.720175390241;5091.261767043832;5111.539961403491;5004.224345226496;5029.145045342374;5040.168372218543;5053.191489361684;5061.848144555669;4988.251762235694;5086.160783470686;5090.287359769562;5132.307138536399;5168.560672342893;5004.524570165829;5004.899902002013;5013.097642424399;5014.724190169576;5045.571126454146 05:04:53 INFO - PROCESS | 31478 | |5;yui.html;659.7196191618561;667.8163505036117;697.1982220446486;707.2609123465977;730.0217708270916;12238.938805305972;12995.545155919544;13119.6720081998;13142.277174755385;13194.340282985848;12978.156419832705;13195.142315749494;13287;13322.666933326671;13360.665983350422;793.4406243598249;810.1007881251805;818.7789296889836;819.3731795176665;827.2843989948681;828.7845160258316;831.2477208126625;831.5260301628044;831.5343407691712;832.8875601793741;862.8576284913013;864.9437786543886;873.9949058582649;875.5315905990296;875.8467268228067;863.1799790199336;863.4517081653132;865.9870101948444;876.1851478125373;879.5338470610586;633.5058654249692;636.5734957578387;640.2381166411955;642.633698791689;643.5237923936301;13735.656608584766;13969.650758731113;13972.091814032055;13998.30008499571;14012.929935350257;12657.114002019862;13002.349882505838;13110.574543709445;13298.40157192919;13359.933200333937;10111.595536178544;10230.897691023143;10256.94871525645;10867.891321086767;11629.837016298387;3194.2653189766484;3235.0038925169656;3240.141362538929;3317.568716066896;3320.6181289151814;60499;60601.39398606046;62050;62356.034551707715;62575;10677.359358438518;10702.304350217211;10735.946320268506;10775.191860610486;10816;20225.494362641053;20239.190432382835;20257.797422025593;20266.493337666383;20347.4739394546;6362.2683391409655;6416.935830641727;6466.321036291121;6476.643784591893;6542.8364290893105;3206.1663967368186;3260.3805276997296;3299.2411745298714;3329.9667003329655;3423.7945723256685;3024.9243768905512;3076.523138913472;3102.9224269392994;3104.425681248976;3109.2226943264186;508.99491005090215;519.8251950591615;527.8996990571779;529.6080900133874;536.5439376529919;591.3081694417542;614.4668158090977;616.1774031667684;630.2562975688639;650.1580453312971;7878.409119316074;8295.377846661524;8357.456765310235;8428.873566896502;8483.44857584255;592.4401440638642;621.273110460762;629.8456878064903;632.2728861808864;635.9682015899186;3976.642102210757;4042.2521833460314;4081.0205550667624;4097.631213190827;4208.452901122834;62687.6865615669;63791.36208637947;64687.67656161689;64942;65312.67343663251;54968;54987.45012549823;56575.81635305684;58417.4158258412;58664.413355865894;51127.48872511302;52423.73788131035;52735;52935.470645293826;53525;7951.244631759973;8156.755297341089;8262.108262108455;8359.540225287668;8490.660373585235;34674.306513870084;35485.112872178404;35854.46218306779;36724.265514690094;37077.258454831295;41018.79490602647;44701.776491117336;44860.551394485636;45096.54903450923;45664.771676141405;10051.547680354548;10157.187425005834;10173.13528350069;10218.693439196835;10492.370457772558;10418.843717344398;10440.477976101316;10528.473576321307;10620.09729173029;10735.570577176826;9365.953170234105;9575.09036641518;9794.118529332394;9951.850722239316;10095.798084038424;9776.217902567636;10023.799524009333;10088.848667269851;10234;10606.575736970435;10787.460626968777;11215.66353009411;11847.822282665938;12021.879781202426;12126.636200913987;8364.581770911553;9146.771330716785;9820.901790982;9883.752906177404;10033.598656053968;9338.673146439842;9568.95215523918;9662.75843103928;9776.315657903879;9957.651482198089 05:04:53 INFO - PROCESS | 31478 | __end_tp_report 05:04:53 INFO - PROCESS | 31478 | __start_cc_report 05:04:53 INFO - PROCESS | 31478 | _x_x_mozilla_cycle_collect,383 05:04:53 INFO - PROCESS | 31478 | __end_cc_report 05:04:53 INFO - PROCESS | 31478 | __startTimestamp1487250293730__endTimestamp 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | ------- Summary: start ------- 05:04:53 INFO - PROCESS | 31478 | Number of tests: 6 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#0] dojo.html Cycles:165 Average:4643.17 Median:4800.66 stddev:498.47 (10.4%) stddev-sans-first:471.32 05:04:53 INFO - PROCESS | 31478 | Values: 2518.8 2526.1 2547.7 2549.9 2570.4 4596.3 4605.4 4625.6 4636.9 4714.7 4664.7 4723.3 4759.9 4779.9 4846.0 4115.5 4373.3 4412.8 4414.3 4434.5 4357.5 4383.3 4384.5 4393.2 4394.0 4174.7 4268.5 4291.9 4384.3 4395.7 4293.6 4301.8 4307.3 4336.7 4348.5 4192.4 4215.0 4215.1 4271.3 4308.7 4576.0 4611.7 4615.1 4647.3 4657.7 5038.5 5039.5 5042.9 5085.1 5115.4 5079.6 5081.2 5087.4 5103.5 5130.3 4613.6 4646.7 4676.3 4768.3 4780.5 5059.0 5124.1 5168.0 5224.8 5236.7 4841.2 4918.1 4944.7 4971.0 4973.7 5017.2 5059.2 5072.8 5074.1 5093.2 4842.5 4886.3 4911.4 5035.4 5037.6 4923.2 4932.8 4965.1 4994.4 4995.3 4260.5 4274.5 4316.7 4321.0 4325.2 3638.2 3668.9 3685.1 3689.0 3691.1 4300.0 4346.8 4367.8 4368.7 4400.0 4917.2 4944.0 5030.6 5039.9 5083.5 4341.8 4344.3 4346.0 4365.2 4421.5 4486.0 4611.7 4613.8 4616.8 4625.3 4757.8 4783.5 4800.7 4804.3 4827.3 4889.3 4911.2 5039.4 5049.4 5068.8 4789.1 4794.2 4795.9 4829.4 4835.8 4992.5 4998.6 5012.0 5022.0 5066.6 4877.5 4893.9 4918.0 4927.6 5102.9 4808.5 4815.1 4853.3 4858.1 4886.2 4770.5 4820.3 4824.7 4854.1 4866.0 4846.8 4905.0 4910.1 4924.2 4958.1 4761.9 4782.4 4832.0 4929.3 4989.0 4849.5 4852.9 4860.6 4898.2 4905.9 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#1] ext.html Cycles:180 Average:42604.58 Median:3389.87 stddev:73492.36 (2168.0%) stddev-sans-first:73630.56 05:04:53 INFO - PROCESS | 31478 | Values: 508.1 509.8 523.5 549.3 570.5 37342.4 39339.4 39387.2 39467.2 39468.8 39081.2 39987.6 40024.2 40064.6 40117.4 7397.2 7632.6 7654.0 7665.6 9485.1 9400.4 9483.0 9492.8 9497.4 9502.1 9514.2 9548.4 9551.7 9557.1 9568.6 9394.0 9401.2 9453.5 9460.2 9461.4 9316.9 9323.5 9355.9 9389.0 9405.0 32692.0 32995.7 33131.7 33193.2 33235.0 3277.0 3329.9 3386.2 3393.6 3414.4 25553.7 26120.0 26120.0 26192.0 26259.7 3301.0 3347.0 3406.0 3555.8 3560.5 227509.0 228607.0 229027.0 231113.0 231259.0 38430.3 39386.4 47941.3 47972.0 48073.0 53524.7 54826.7 54890.2 54988.0 55025.7 12355.7 12473.8 12506.6 12556.9 12628.7 9584.6 9626.2 9687.7 9703.1 9749.6 15869.3 15916.7 15950.4 15962.5 15973.6 6474.4 6542.4 6583.5 6583.8 6617.7 744.2 745.2 750.7 762.6 765.4 25932.0 26866.1 27137.3 27267.6 27320.7 932.6 937.1 941.8 947.3 1013.3 14302.9 14413.3 14546.3 14597.1 14792.4 325042.0 325309.0 327266.0 327298.0 330984.0 48879.3 49585.8 49858.5 49906.5 49925.8 1143.4 1174.8 1177.3 1178.7 1186.6 24817.8 25713.4 26368.7 26718.9 26736.3 41205.2 41249.2 41303.2 41317.6 41462.0 274810.0 275760.0 276116.5 276263.0 277306.0 22688.2 23901.2 23924.4 24933.4 25065.0 35790.3 35844.6 35873.1 36276.8 36667.3 30713.2 31380.0 31664.0 31735.7 32447.5 30753.0 30916.5 30974.6 31181.4 31326.8 31274.5 31507.8 31679.4 31740.7 31939.0 26878.2 28438.0 28551.9 28599.4 28647.9 21970.5 22115.1 22334.4 22503.7 22571.5 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#2] jquery.html Cycles:180 Average:27038.98 Median:5167.04 stddev:80982.17 (1567.3%) stddev-sans-first:81184.62 05:04:53 INFO - PROCESS | 31478 | Values: 392.7 395.6 399.4 404.0 417.4 16966.3 17091.1 17559.0 17666.5 17689.7 17316.3 17405.7 17557.1 17826.0 18016.7 4883.2 4911.5 5073.7 5086.6 5089.4 5035.5 5162.4 5182.4 5188.7 5194.8 5150.8 5153.8 5171.7 5175.4 5178.3 5400.7 5649.4 5691.1 5723.8 5749.8 5450.4 5562.5 5589.4 5590.1 5616.6 11043.3 11160.9 11179.5 11226.8 11274.3 2384.5 2412.0 2426.3 2433.3 2454.4 9570.2 9640.5 10140.5 10183.6 12187.9 1723.9 1740.7 1755.9 1759.2 1789.5 70741.3 73871.3 76276.2 77547.6 77969.0 10613.6 10856.5 10861.4 10922.9 10962.4 15242.0 15292.6 15300.2 15320.9 15345.5 6692.3 7064.5 7097.8 7152.9 7220.6 6324.9 6362.0 6401.7 6493.3 6542.9 4583.3 4691.7 4709.7 4718.8 4813.2 1955.3 1956.2 1961.2 1973.0 2004.1 856.9 885.8 887.6 888.4 912.8 15383.8 16392.6 16490.2 16755.5 17623.6 866.5 871.6 879.6 880.5 883.9 9207.3 9259.6 9461.1 9541.5 9631.7 485354.0 487275.0 493132.0 497529.0 501749.0 56209.7 73182.0 74078.0 80917.0 82092.0 880.5 912.9 932.2 943.5 945.3 14383.6 14493.6 14631.3 14854.0 15248.8 37255.6 37263.3 37841.4 37928.0 38165.8 50742.5 53090.2 53201.2 53840.7 54074.7 7657.6 7720.0 7732.7 7776.5 8155.1 8938.9 11478.5 11979.6 12012.9 12074.8 8855.7 9139.7 9171.0 9290.3 9295.7 8828.9 10107.9 10281.9 10378.3 10706.7 5571.9 7223.7 8132.9 9687.4 9959.9 7726.2 7886.0 7964.4 8070.4 8172.4 9288.7 9353.7 9398.3 9434.7 9761.0 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#3] mootools.html Cycles:180 Average:4924.79 Median:4709.01 stddev:2763.56 (58.7%) stddev-sans-first:2758.22 05:04:53 INFO - PROCESS | 31478 | Values: 1344.7 1369.6 1374.2 1376.3 1382.0 7175.9 7520.5 7581.8 7590.8 7721.9 7749.0 7850.3 7874.3 7882.8 7909.2 2095.3 2132.5 2147.1 2150.6 2155.6 2067.8 2100.8 2104.8 2106.9 2117.8 2087.3 2101.3 2113.1 2114.8 2127.4 2051.7 2065.5 2066.0 2069.6 2091.5 1800.8 1851.7 2045.0 2123.9 2155.7 7548.8 7791.5 7813.1 7952.8 7955.0 4565.8 4696.7 4702.8 4711.4 4712.7 2928.5 2995.0 3029.6 3044.4 3050.8 1147.7 1166.4 1172.9 1176.9 1206.0 10253.9 10270.0 10344.3 10347.7 10368.6 3830.6 4009.0 4046.7 4058.5 4086.5 8324.1 8355.9 8396.0 8406.0 8409.5 4590.0 4592.9 4593.7 4596.2 4597.9 3970.2 4018.8 4116.5 4131.3 4173.6 3332.9 3363.6 3428.5 3430.0 3454.8 1004.0 1031.6 1047.3 1049.7 1056.8 760.2 788.0 793.6 793.9 803.4 4578.8 4636.9 4706.6 4804.8 4875.6 503.7 510.5 517.5 518.9 529.8 2972.7 3002.7 3143.6 3342.0 3415.3 8329.6 8362.7 8593.1 8655.7 9287.9 8898.5 8902.0 8933.9 8934.2 8946.0 7007.5 7228.2 7337.3 7352.4 7353.5 5019.5 5062.5 5174.4 5265.2 5294.5 8631.5 8675.9 8712.2 8750.2 8798.0 8422.2 8655.9 8737.9 8763.1 8792.3 6627.7 6696.2 6705.9 6756.6 6790.5 7183.5 7206.2 7312.9 7320.2 7339.9 6453.5 6524.5 6534.3 6649.5 6746.5 6419.0 6428.5 6447.9 6555.4 6576.7 5422.5 5493.1 5498.3 5503.0 5507.4 4109.6 4177.1 4256.7 4261.0 4324.0 4192.7 4276.7 4355.2 4356.3 4372.2 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#4] prototype.html Cycles:180 Average:4201.00 Median:4782.36 stddev:1205.63 (25.2%) stddev-sans-first:1173.98 05:04:53 INFO - PROCESS | 31478 | Values: 356.9 359.3 361.9 362.9 366.5 4832.9 4865.7 4903.5 4913.2 4914.2 4811.3 4852.8 4873.9 4885.7 4891.6 3649.2 3787.5 3826.2 3826.9 3840.2 3795.9 3799.7 3808.7 3814.4 3823.0 3791.5 3798.3 3803.7 3808.5 3834.4 3715.7 3801.5 3819.4 3841.8 3855.3 3322.7 3326.0 3341.6 3342.0 3349.8 4710.2 4728.8 4729.5 4735.3 4779.9 4313.5 4337.4 4347.3 4357.0 4362.2 4982.6 4994.8 5012.4 5052.0 5078.9 3620.9 3641.2 3651.8 3655.8 3661.9 5111.2 5204.7 5377.7 5396.0 5417.9 3620.8 3698.9 3709.2 3712.3 3725.5 3750.7 3765.2 3776.5 3778.0 3788.9 4291.0 4319.7 4330.3 4337.9 4346.7 5033.6 5101.4 5125.9 5133.8 5174.7 1265.9 1278.0 1278.4 1279.9 1287.3 827.3 867.3 872.3 874.8 875.7 4328.3 4355.2 4360.8 4361.6 4364.6 4989.8 5113.6 5159.6 5166.3 5189.7 4197.6 4351.4 4366.7 4391.5 4410.8 2720.4 2729.9 2733.3 2737.5 2749.2 4808.9 4809.8 4831.8 4848.8 4863.4 4984.8 5098.5 5172.9 5186.5 5196.5 4745.3 4767.7 4771.5 4776.3 4790.9 5075.7 5077.7 5085.0 5106.2 5125.9 4768.9 5036.9 5042.6 5043.2 5067.8 4781.8 4783.0 4788.3 4802.5 4936.2 3717.1 3735.1 3739.9 3742.7 3755.2 5214.3 5226.3 5251.2 5265.6 5278.7 4932.7 5047.7 5103.8 5109.7 5150.9 5077.8 5085.3 5087.7 5091.3 5111.5 5004.2 5029.1 5040.2 5053.2 5061.8 4988.3 5086.2 5090.3 5132.3 5168.6 5004.5 5004.9 5013.1 5014.7 5045.6 05:04:53 INFO - PROCESS | 31478 | 05:04:53 INFO - PROCESS | 31478 | [#5] yui.html Cycles:180 Average:14577.87 Median:26631.45 stddev:18177.41 (68.3%) stddev-sans-first:18198.35 05:04:53 INFO - PROCESS | 31478 | Values: 659.7 667.8 697.2 707.3 730.0 12238.9 12995.5 13119.7 13142.3 13194.3 12978.2 13195.1 13287.0 13322.7 13360.7 793.4 810.1 818.8 819.4 827.3 828.8 831.2 831.5 831.5 832.9 862.9 864.9 874.0 875.5 875.8 863.2 863.5 866.0 876.2 879.5 633.5 636.6 640.2 642.6 643.5 13735.7 13969.7 13972.1 13998.3 14012.9 12657.1 13002.3 13110.6 13298.4 13359.9 10111.6 10230.9 10256.9 10867.9 11629.8 3194.3 3235.0 3240.1 3317.6 3320.6 60499.0 60601.4 62050.0 62356.0 62575.0 10677.4 10702.3 10735.9 10775.2 10816.0 20225.5 20239.2 20257.8 20266.5 20347.5 6362.3 6416.9 6466.3 6476.6 6542.8 3206.2 3260.4 3299.2 3330.0 3423.8 3024.9 3076.5 3102.9 3104.4 3109.2 509.0 519.8 527.9 529.6 536.5 591.3 614.5 616.2 630.3 650.2 7878.4 8295.4 8357.5 8428.9 8483.4 592.4 621.3 629.8 632.3 636.0 3976.6 4042.3 4081.0 4097.6 4208.5 62687.7 63791.4 64687.7 64942.0 65312.7 54968.0 54987.5 56575.8 58417.4 58664.4 51127.5 52423.7 52735.0 52935.5 53525.0 7951.2 8156.8 8262.1 8359.5 8490.7 34674.3 35485.1 35854.5 36724.3 37077.3 41018.8 44701.8 44860.6 45096.5 45664.8 10051.5 10157.2 10173.1 10218.7 10492.4 10418.8 10440.5 10528.5 10620.1 10735.6 9366.0 9575.1 9794.1 9951.9 10095.8 9776.2 10023.8 10088.8 10234.0 10606.6 10787.5 11215.7 11847.8 12021.9 12126.6 8364.6 9146.8 9820.9 9883.8 10033.6 9338.7 9569.0 9662.8 9776.3 9957.7 05:04:53 INFO - PROCESS | 31478 | -------- Summary: end -------- 05:04:53 INFO - PROCESS | 31478 | 05:04:54 INFO - TEST-INFO | 31478: exit 0 05:04:54 INFO - TEST-OK | dromaeo_css | took 1119573ms 05:04:54 INFO - TEST-START | kraken 05:04:54 INFO - operating with platform_type : linux_ 05:04:54 INFO - Initialising browser for kraken test... 05:04:54 INFO - TEST-INFO | started process 31832 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf9OpQa/profile http://localhost:60683/getInfo.html) 05:04:57 INFO - PROCESS | 31832 | Iterating over profile /tmp/tmpf9OpQa/profile 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/pluginreg.dat = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/prefs.js = Thu Feb 16 2017 04:45:31 GMT-0800 (PST) 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/cache2 = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/places.sqlite-wal = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/places.sqlite = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:58 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:58 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/mimeTypes.rdf = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/safebrowsing = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/directoryLinks.json = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/secmod.db = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | __metrics Screen width/height:1600/1200 05:04:59 INFO - PROCESS | 31832 | colorDepth:24 05:04:59 INFO - PROCESS | 31832 | Browser inner width/height: 1024/697 05:04:59 INFO - PROCESS | 31832 | __metrics 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/localstore.rdf = Thu Feb 16 2017 04:45:31 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | Stat failure 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/frequencyCap.json = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/.parentlock = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/thumbnails = Thu Feb 16 2017 05:04:56 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/extensions.json = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/sessionCheckpoints.json = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | Stat failure 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/startupCache = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/extensions.ini = Thu Feb 16 2017 05:04:55 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/crashes = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/bookmarkbackups = Thu Feb 16 2017 05:04:56 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/minidumps = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/user.js = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | Stat failure 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/blocklist.xml = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/gmp = Thu Feb 16 2017 05:04:55 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/extensions = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/key3.db = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/content-prefs.sqlite = Thu Feb 16 2017 05:04:56 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/addons.json = Thu Feb 16 2017 05:04:57 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/storage.sqlite = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/webappsstore.sqlite = Thu Feb 16 2017 05:04:58 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/places.sqlite-shm = Thu Feb 16 2017 05:04:56 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/permissions.sqlite = Thu Feb 16 2017 05:04:55 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/cert8.db = Thu Feb 16 2017 05:04:54 GMT-0800 (PST) 05:04:59 INFO - PROCESS | 31832 | No birth date. Using mtime. 05:04:59 INFO - PROCESS | 31832 | Using date: /tmp/tmpf9OpQa/profile/compatibility.ini = Thu Feb 16 2017 05:04:55 GMT-0800 (PST) 05:04:59 ERROR - PROCESS | 31832 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 05:05:00 INFO - TEST-INFO | 31832: exit 0 05:05:00 INFO - Browser initialized. 05:05:00 INFO - Running cycle 1/1 for kraken test... 05:05:00 INFO - TEST-INFO | started process 31954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf9OpQa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:12 INFO - PROCESS | 31954 | RSS: Main: 160149504 05:05:12 INFO - PROCESS | 31954 | 05:05:15 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60683/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:16 INFO - PROCESS | 31954 | RSS: Main: 166547456 05:05:16 INFO - PROCESS | 31954 | 05:05:19 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60683/tests/kraken/driver.html?testName=audio-dft) 05:05:20 INFO - PROCESS | 31954 | RSS: Main: 166178816 05:05:20 INFO - PROCESS | 31954 | 05:05:23 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60683/tests/kraken/driver.html?testName=audio-fft) 05:05:23 INFO - PROCESS | 31954 | RSS: Main: 163127296 05:05:23 INFO - PROCESS | 31954 | 05:05:25 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60683/tests/kraken/driver.html?testName=audio-oscillator) 05:05:26 INFO - PROCESS | 31954 | RSS: Main: 163647488 05:05:26 INFO - PROCESS | 31954 | 05:05:28 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60683/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:29 INFO - PROCESS | 31954 | RSS: Main: 163487744 05:05:29 INFO - PROCESS | 31954 | 05:05:32 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60683/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:33 INFO - PROCESS | 31954 | RSS: Main: 167829504 05:05:33 INFO - PROCESS | 31954 | 05:05:36 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60683/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:37 INFO - PROCESS | 31954 | RSS: Main: 165228544 05:05:37 INFO - PROCESS | 31954 | 05:05:40 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60683/tests/kraken/driver.html?testName=json-parse-financial) 05:05:40 INFO - PROCESS | 31954 | RSS: Main: 165535744 05:05:40 INFO - PROCESS | 31954 | 05:05:42 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60683/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:43 INFO - PROCESS | 31954 | RSS: Main: 165990400 05:05:43 INFO - PROCESS | 31954 | 05:05:45 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:45 INFO - PROCESS | 31954 | RSS: Main: 166326272 05:05:45 INFO - PROCESS | 31954 | 05:05:47 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:48 INFO - PROCESS | 31954 | RSS: Main: 166391808 05:05:48 INFO - PROCESS | 31954 | 05:05:51 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:52 INFO - PROCESS | 31954 | RSS: Main: 166449152 05:05:52 INFO - PROCESS | 31954 | 05:05:55 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:55 INFO - PROCESS | 31954 | RSS: Main: 169422848 05:05:55 INFO - PROCESS | 31954 | 05:05:57 INFO - PROCESS | 31954 | Cycle 1(1): loaded http://localhost:60683/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60683/tests/kraken/driver.html?testName=ai-astar) 05:05:58 INFO - PROCESS | 31954 | RSS: Main: 171208704 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | __start_tp_report 05:05:58 INFO - PROCESS | 31954 | _x_x_mozilla_page_load 05:05:58 INFO - PROCESS | 31954 | _x_x_mozilla_page_load_details 05:05:58 INFO - PROCESS | 31954 | |i|pagename|runs| 05:05:58 INFO - PROCESS | 31954 | |0;ai-astar;108;116;117;116;119;116;116;117;117;118 05:05:58 INFO - PROCESS | 31954 | |1;audio-beat-detection;157;132;135;170;131;135;130;130;158;137 05:05:58 INFO - PROCESS | 31954 | |2;audio-dft;166;169;171;172;165;166;165;166;165;165 05:05:58 INFO - PROCESS | 31954 | |3;audio-fft;78;77;77;119;78;78;79;79;100;78 05:05:58 INFO - PROCESS | 31954 | |4;audio-oscillator;85;85;108;84;85;85;99;84;84;111 05:05:58 INFO - PROCESS | 31954 | |5;imaging-gaussian-blur;114;114;114;114;117;114;114;114;114;114 05:05:58 INFO - PROCESS | 31954 | |6;imaging-darkroom;152;151;152;152;152;151;152;152;153;151 05:05:58 INFO - PROCESS | 31954 | |7;imaging-desaturate;96;99;96;97;96;97;96;97;97;96 05:05:58 INFO - PROCESS | 31954 | |8;json-parse-financial;67;67;66;66;67;73;68;69;66;69 05:05:58 INFO - PROCESS | 31954 | |9;json-stringify-tinderbox;65;66;66;67;67;66;70;65;66;67 05:05:58 INFO - PROCESS | 31954 | |10;stanford-crypto-aes;89;88;99;88;88;89;91;86;87;86 05:05:58 INFO - PROCESS | 31954 | |11;stanford-crypto-ccm;138;159;150;156;148;140;144;144;200;140 05:05:58 INFO - PROCESS | 31954 | |12;stanford-crypto-pbkdf2;191;188;187;189;192;187;196;185;187;194 05:05:58 INFO - PROCESS | 31954 | |13;stanford-crypto-sha256-iterative;76;80;71;70;71;77;70;71;70;69 05:05:58 INFO - PROCESS | 31954 | __end_tp_report 05:05:58 INFO - PROCESS | 31954 | __start_cc_report 05:05:58 INFO - PROCESS | 31954 | _x_x_mozilla_cycle_collect,849 05:05:58 INFO - PROCESS | 31954 | __end_cc_report 05:05:58 INFO - PROCESS | 31954 | __startTimestamp1487250358057__endTimestamp 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | ------- Summary: start ------- 05:05:58 INFO - PROCESS | 31954 | Number of tests: 14 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.00 stddev:2.98 (2.5%) stddev-sans-first:1.05 05:05:58 INFO - PROCESS | 31954 | Values: 108.0 116.0 117.0 116.0 119.0 116.0 116.0 117.0 117.0 118.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:136.00 stddev:14.51 (10.7%) stddev-sans-first:14.26 05:05:58 INFO - PROCESS | 31954 | Values: 157.0 132.0 135.0 170.0 131.0 135.0 130.0 130.0 158.0 137.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#2] audio-dft Cycles:10 Average:167.00 Median:166.00 stddev:2.67 (1.6%) stddev-sans-first:2.80 05:05:58 INFO - PROCESS | 31954 | Values: 166.0 169.0 171.0 172.0 165.0 166.0 165.0 166.0 165.0 165.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#3] audio-fft Cycles:10 Average:84.30 Median:78.00 stddev:14.03 (18.0%) stddev-sans-first:14.70 05:05:58 INFO - PROCESS | 31954 | Values: 78.0 77.0 77.0 119.0 78.0 78.0 79.0 79.0 100.0 78.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:85.00 stddev:10.77 (12.7%) stddev-sans-first:11.20 05:05:58 INFO - PROCESS | 31954 | Values: 85.0 85.0 108.0 84.0 85.0 85.0 99.0 84.0 84.0 111.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 05:05:58 INFO - PROCESS | 31954 | Values: 114.0 114.0 114.0 114.0 117.0 114.0 114.0 114.0 114.0 114.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:05:58 INFO - PROCESS | 31954 | Values: 152.0 151.0 152.0 152.0 152.0 151.0 152.0 152.0 153.0 151.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.00 stddev:0.95 (1.0%) stddev-sans-first:0.97 05:05:58 INFO - PROCESS | 31954 | Values: 96.0 99.0 96.0 97.0 96.0 97.0 96.0 97.0 97.0 96.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:67.50 stddev:2.15 (3.2%) stddev-sans-first:2.26 05:05:58 INFO - PROCESS | 31954 | Values: 67.0 67.0 66.0 66.0 67.0 73.0 68.0 69.0 66.0 69.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.50 stddev:1.43 (2.2%) stddev-sans-first:1.41 05:05:58 INFO - PROCESS | 31954 | Values: 65.0 66.0 66.0 67.0 67.0 66.0 70.0 65.0 66.0 67.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:88.50 stddev:3.78 (4.3%) stddev-sans-first:4.01 05:05:58 INFO - PROCESS | 31954 | Values: 89.0 88.0 99.0 88.0 88.0 89.0 91.0 86.0 87.0 86.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:149.00 stddev:18.26 (12.3%) stddev-sans-first:18.66 05:05:58 INFO - PROCESS | 31954 | Values: 138.0 159.0 150.0 156.0 148.0 140.0 144.0 144.0 200.0 140.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.60 Median:190.00 stddev:3.53 (1.9%) stddev-sans-first:3.71 05:05:58 INFO - PROCESS | 31954 | Values: 191.0 188.0 187.0 189.0 192.0 187.0 196.0 185.0 187.0 194.0 05:05:58 INFO - PROCESS | 31954 | 05:05:58 INFO - PROCESS | 31954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:71.00 stddev:3.75 (5.3%) stddev-sans-first:3.76 05:05:58 INFO - PROCESS | 31954 | Values: 76.0 80.0 71.0 70.0 71.0 77.0 70.0 71.0 70.0 69.0 05:05:58 INFO - PROCESS | 31954 | -------- Summary: end -------- 05:05:58 INFO - PROCESS | 31954 | 05:05:59 INFO - TEST-INFO | 31954: exit 0 05:05:59 INFO - TEST-OK | kraken | took 64766ms 05:05:59 INFO - SUITE-END | took 1184s 05:05:59 INFO - Completed test suite (00:19:44) 05:05:59 INFO - javascript benchmark 05:05:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6245.406001733855, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2518.8492898409368, 2526.052730226165, 2547.694276686798, 2549.859757713326, 2570.4216551275954, 4596.287575425806, 4605.355250264962, 4625.629949604032, 4636.884077898054, 4714.717116972984, 4664.696794708341, 4723.315119307698, 4759.90480190396, 4779.856604301868, 4846.030793841228, 4115.526714427834, 4373.322135069061, 4412.8455504057365, 4414.29371300592, 4434.490033646123, 4357.520672725997, 4383.320585309282, 4384.473863136413, 4393.231184542708, 4393.956060439403, 4174.749515029078, 4268.487781466244, 4291.914161716748, 4384.276594361926, 4395.669818715808, 4293.635041021517, 4301.806418711166, 4307.267764480014, 4336.718113322624, 4348.543402942709, 4192.413062171298, 4214.978925105354, 4215.135897141079, 4271.337942618899, 4308.67684923632, 4576.0, 4611.700239484423, 4615.123126605934, 4647.326137710047, 4657.69724967876, 5038.5465308122075, 5039.546440820307, 5042.899142017176, 5085.059264036166, 5115.386153661547, 5079.568236699885, 5081.237814327844, 5087.414947281073, 5103.464136265676, 5130.256112863613, 4613.607843333287, 4646.651501137429, 4676.3453116563705, 4768.284757286435, 4780.528911691066, 5059.0, 5124.12889808726, 5167.974160129175, 5224.791008359699, 5236.659617124875, 4841.225403935424, 4918.065567542156, 4944.703317800943, 4971.005798840174, 4973.651844370932, 5017.247412888096, 5059.241113832954, 5072.82245121419, 5074.112030394666, 5093.159628661304, 4842.491538388418, 4886.291487734258, 4911.4106307243355, 5035.446100928895, 5037.622178336639, 4923.163062279421, 4932.802687892444, 4965.081459929925, 4994.375703037121, 4995.275685025651, 4260.531341552427, 4274.487061552633, 4316.7409955402145, 4320.9629688875075, 4325.199838029975, 3638.21778317663, 3668.944965825515, 3685.1155722626377, 3689.0, 3691.1325838427947, 4299.97850010748, 4346.826126954887, 4367.759773212504, 4368.694191406635, 4399.956000439955, 4917.188663870493, 4943.975280123648, 5030.572401345853, 5039.949600503948, 5083.517065878736, 4341.782910854445, 4344.261475549164, 4345.978270108692, 4365.236083685342, 4421.53573874745, 4486.0, 4611.700239484423, 4613.754286342669, 4616.815327386934, 4625.259958406638, 4757.833475828329, 4783.521647835189, 4800.66395352322, 4804.327394164819, 4827.275908613736, 4889.315495830516, 4911.214205727067, 5039.395272567315, 5049.444561098203, 5068.771905264346, 4789.066132104206, 4794.184988552024, 4795.904081918412, 4829.420469543677, 4835.758212089311, 4992.450830408725, 4998.550130488274, 5012.04771093491, 5021.974890125526, 5066.594672426123, 4877.5366340197625, 4893.90212195747, 4918.0, 4927.605791536596, 5102.897942041212, 4808.519148085164, 4815.109204797123, 4853.272009198648, 4858.076965376567, 4886.242632391985, 4770.522947705202, 4820.25286080658, 4824.734639594715, 4854.077725232195, 4866.026794641157, 4846.80612775485, 4904.950950490449, 4910.091633047898, 4924.236743304794, 4958.1323268428605, 4761.857144285677, 4782.354382158364, 4831.95168048315, 4929.334539837219, 4988.975055124701, 4849.515048495264, 4852.927206091982, 4860.562549370574, 4898.191798353232, 4905.852824415273], "value": 4609.974901564449, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.1209507551935, 509.8317555206782, 523.5026724611618, 549.2530158983783, 570.5293133165135, 37342.43986340204, 39339.40990885139, 39387.21225575494, 39467.210655786905, 39468.80265598668, 39081.218375632474, 39987.60012399875, 40024.19951600966, 40064.59935400645, 40117.398239026435, 7397.150712321906, 7632.6183690815515, 7654.043244594426, 7665.616719164047, 9485.146336829685, 9400.388974716656, 9482.952585237064, 9492.762680932963, 9497.430154190735, 9502.144806967372, 9514.191293740041, 9548.37935534188, 9551.665691700791, 9557.092076252746, 9568.617255309779, 9393.953030234874, 9401.153896149344, 9453.52732363379, 9460.24318054554, 9461.432314061178, 9316.906830931672, 9323.487208203545, 9355.906440935572, 9388.953055234748, 9405.0, 32692.0, 32995.6700432995, 33131.66868331334, 33193.17017074568, 33235.0, 3277.041810876387, 3329.8501567429525, 3386.1703882548686, 3393.575803024622, 3414.3854106260865, 25553.744462555507, 26120.0, 26120.0, 26192.0, 26259.73740262592, 3301.0, 3346.966530334714, 3405.9659403406145, 3555.7866528008144, 3560.4659301104784, 227509.0, 228607.0, 229027.0, 231113.0, 231259.0, 38430.27063782136, 39386.409203861964, 47941.28088078681, 47972.0, 48073.0, 53524.732376337866, 54826.72586637041, 54890.17664735032, 54988.0, 55025.72487137539, 12355.69110772238, 12473.75052498945, 12506.624801255977, 12556.874431255754, 12628.684282893002, 9584.616615335448, 9626.229901607854, 9687.66093186735, 9703.07820757027, 9749.610015599439, 15869.285882135331, 15916.681666366609, 15950.441734539236, 15962.521124366056, 15973.600659983362, 6474.417302442803, 6542.411182993554, 6583.539152259392, 6583.769568065095, 6617.702203400859, 744.1999850161098, 745.1505283976271, 750.7147284032033, 762.6453699029978, 765.4221063097326, 25932.0, 26866.05968791083, 27137.321566960985, 27267.590986135223, 27320.726792731817, 932.5570354081757, 937.1144268666045, 941.8304705153138, 947.2706016367496, 1013.2704452793977, 14302.928485357506, 14413.30797150303, 14546.272686365639, 14597.12417254947, 14792.408303667948, 325042.0, 325309.0, 327266.0, 327298.0, 330984.0, 48879.26681099786, 49585.75207124013, 49858.50141498539, 49906.50093499091, 49925.750371248636, 1143.3997151495364, 1174.7826652069223, 1177.2936238256978, 1178.6935396796723, 1186.5846953566356, 24817.759112044685, 25713.35716607025, 26368.73631263663, 26718.931242750074, 26736.331591709586, 41205.1758964825, 41249.1750164989, 41303.1739365217, 41317.58682413137, 41462.0, 274810.0, 275760.0, 276116.4854393201, 276263.0, 277306.0, 22688.20591279297, 23901.163459278843, 23924.401889952194, 24933.376665583506, 25065.0, 35790.28419431649, 35844.64155358413, 35873.103172420895, 36276.81861590675, 36667.26665466622, 30713.232169195948, 31380.0, 31664.0, 31735.682643173273, 32447.513287301183, 30753.0, 30916.53625195579, 30974.606142724082, 31181.376372472878, 31326.843365783025, 31274.53088203724, 31507.84246078755, 31679.36641267208, 31740.682593173773, 31939.0, 26878.193654190407, 28438.0, 28551.857240713663, 28599.42801144007, 28647.856760716895, 21970.45073873166, 22115.115395384648, 22334.441638958506, 22503.662445063666, 22571.548569028855], "value": 16680.213153471486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.7407910778887, 395.6182284095847, 399.4028926754502, 403.9798010099495, 417.3975264227541, 16966.321347146113, 17091.145442727844, 17559.0, 17666.470005899828, 17689.734653980184, 17316.307347706075, 17405.738913916302, 17557.12214389282, 17826.0, 18016.729749053775, 4883.243097319921, 4911.45973942866, 5073.695578265298, 5086.643934924538, 5089.414717307502, 5035.496450354972, 5162.380514338284, 5182.378114626231, 5188.688678679291, 5194.792208311682, 5150.768215430315, 5153.768080436389, 5171.689698618095, 5175.430702622709, 5178.300929374543, 5400.729963501809, 5649.350324712649, 5691.08942569189, 5723.7996670116345, 5749.82750517481, 5450.400455949843, 5562.4993750562235, 5589.385167631557, 5590.133529302964, 5616.606837521375, 11043.337399756041, 11160.888391116066, 11179.49692263842, 11226.775464490664, 11274.32354058751, 2384.511175209078, 2411.9758802412102, 2426.2842461473733, 2433.318670772169, 2454.4088824331243, 9570.186534144606, 9640.517974101267, 10140.492975351277, 10183.59265629374, 12187.93906030473, 1723.8534724548429, 1740.7040803063383, 1755.8858674186265, 1759.1643969114696, 1789.5168304557826, 70741.2925870745, 73871.26128738698, 76276.23723762803, 77547.61226193889, 77969.0, 10613.620229370132, 10856.457177141112, 10861.40262285582, 10922.890771092187, 10962.397068161328, 15242.0, 15292.617684557752, 15300.234988250542, 15320.863366890877, 15345.539633811004, 6692.263850976388, 7064.540804847668, 7097.751578694721, 7152.9284707152265, 7220.638968051681, 6324.873502529924, 6362.0, 6401.711922963478, 6493.285738568754, 6542.934570654328, 4583.2666773316105, 4691.695039822469, 4709.693869898446, 4718.811247550128, 4813.15769740301, 1955.3058664174298, 1956.1686283329811, 1961.1665042356942, 1972.9714887647392, 2004.1499571038123, 856.905740368559, 885.7564169853342, 887.5917078143997, 888.3958907942532, 912.7946212102356, 15383.769243461356, 16392.590185245463, 16490.17549122539, 16755.497335079966, 17623.647527049387, 866.5147517390282, 871.5685735560938, 879.5909901895649, 880.4717169698258, 883.8630012348096, 9207.26341892647, 9259.583318750672, 9461.148496635336, 9541.52292385378, 9631.71104866855, 485354.0, 487275.0, 493132.0, 497529.0, 501749.0, 56209.71895140498, 73182.0, 74078.0, 80917.0, 82092.0, 880.5421180985979, 912.9041450647719, 932.2215949682092, 943.4622265308708, 945.2532499325594, 14383.568492945229, 14493.565193044225, 14631.341589628706, 14854.0, 15248.847511524742, 37255.627443725214, 37263.25473490569, 37841.4323785138, 37928.0, 38165.809170953966, 50742.492575073775, 53090.20364694455, 53201.20198197108, 53840.730796345764, 54074.72962635162, 7657.57883316423, 7719.961400192963, 7732.729354472566, 7776.533407995538, 8155.062167850536, 8938.927328720596, 11478.483468244116, 11979.580714674945, 12012.939935300268, 12074.818877717016, 8855.690050848189, 9139.67474716149, 9171.0, 9290.349675522648, 9295.72112836616, 8828.911710882809, 10107.898921010696, 10281.897181028095, 10378.325408848401, 10706.678799635712, 5571.860703482445, 7223.747168849065, 8132.861399404206, 9687.41875487473, 9959.900400995897, 7726.218790116447, 7886.0, 7964.402669799672, 8070.394720395876, 8172.42793004484, 9288.674896378594, 9353.672621458216, 9398.34211605181, 9434.716958491255, 9760.951195243979], "value": 8198.589535042829, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.7243315120397, 1369.6370461827617, 1374.1823614949108, 1376.291210026837, 1381.9861801381987, 7175.856482870339, 7520.473566850323, 7581.848363032736, 7590.848183036343, 7721.9227807722045, 7748.96125519373, 7850.2542258485355, 7874.251946065115, 7882.763517094496, 7909.169537198583, 2095.2561840546614, 2132.4882028313173, 2147.1196809308185, 2150.6451435513122, 2155.622766015949, 2067.762207346152, 2100.7584127825266, 2104.8105670489654, 2106.9367918962457, 2117.756458007326, 2087.279888438486, 2101.254054810543, 2113.0913707106015, 2114.8202402795705, 2127.393692797553, 2051.6717325227964, 2065.5042789730505, 2066.0496171761006, 2069.6481598128203, 2091.466675997626, 1800.7659004329485, 1851.7037274036225, 2044.9979510039973, 2123.893805309728, 2155.708979287792, 7548.849023019564, 7791.532508049535, 7813.101493328283, 7952.761417157494, 7955.0, 4565.794539245742, 4696.718196908197, 4702.8118875244945, 4711.363965864599, 4712.717236965793, 2928.5314349703945, 2995.0, 3029.59100521429, 3044.360684256309, 3050.771192160597, 1147.713071732067, 1166.4109624639589, 1172.929624222541, 1176.9176157668967, 1206.0291465370374, 10253.897461025368, 10270.0, 10344.327618704836, 10347.689569312932, 10368.63709770162, 3830.616938306203, 4009.0, 4046.736962097454, 4058.512978442606, 4086.4687590613626, 8324.125966773523, 8355.874661880076, 8396.0, 8406.0, 8409.537475438787, 4590.036092420562, 4592.908141837144, 4593.655475839326, 4596.241620132708, 4597.908041839144, 3970.2456533259165, 4018.799060046986, 4116.469842276291, 4131.338985762263, 4173.603507666767, 3332.9333413331597, 3363.613184483767, 3428.5200071989943, 3429.9657003430148, 3454.809985450824, 1003.9648612298535, 1031.582209205273, 1047.324475713176, 1049.6693541534391, 1056.7569459024467, 760.2435576601317, 787.9645415956296, 793.5714714054346, 793.9444238903337, 803.4295650088503, 4578.839740609063, 4636.907261854744, 4706.647001474903, 4804.8318308859725, 4875.634327425457, 503.68771361755944, 510.49913255030094, 517.5238780322113, 518.9143791274397, 529.8463445600732, 2972.7473164781236, 3002.7297543221216, 3143.6227652681823, 3341.966580334214, 3415.316936612687, 8329.625166867505, 8362.749117526484, 8593.097724738938, 8655.74032779005, 9287.907120928705, 8898.510581917928, 8902.0, 8933.910660893438, 8934.151255630704, 8946.0, 7007.509474336747, 7228.241034691387, 7337.3029562191505, 7352.448566357544, 7353.52202106872, 5019.534736132361, 5062.507797878977, 5174.353205849269, 5265.236540701653, 5294.470552944675, 8631.482111073356, 8675.869861952202, 8712.21590056898, 8750.168733970262, 8798.0, 8422.24199822019, 8655.91344086551, 8737.868931966153, 8763.123687631185, 8792.29661627081, 6627.668616569056, 6696.1629796275465, 6705.899411508929, 6756.560823546453, 6790.524663273523, 7183.5330703504105, 7206.207317195209, 7312.853742925218, 7320.15818180916, 7339.853202935805, 6453.515986300952, 6524.510661700297, 6534.346565343428, 6649.46804255668, 6746.5277430581355, 6419.0, 6428.453581445629, 6447.9032814508755, 6555.410013098844, 6576.704048317742, 5422.48486393792, 5493.148561972901, 5498.340199176125, 5503.009458297384, 5507.366652834975, 4109.589041095986, 4177.143685544517, 4256.680748943903, 4261.041265715189, 4323.956760432481, 4192.664586833107, 4276.679249056271, 4355.194289056535, 4356.302991521468, 4372.169287835428], "value": 3934.2927159350807, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.8776198854604, 359.27068051854735, 361.85887503873477, 362.92378600493885, 366.5161986178244, 4832.927506087407, 4865.68373055752, 4903.485134060926, 4913.213885778272, 4914.189158788796, 4811.326414302001, 4852.781624826883, 4873.853784386465, 4885.706857588547, 4891.584215341692, 3649.1606930405956, 3787.450819631166, 3826.1773718650484, 3826.9425958610504, 3840.1743625120594, 3795.867144649938, 3799.6960243180615, 3808.733388662795, 3814.3788889721895, 3823.0, 3791.526059242595, 3798.2973149967343, 3803.695704343646, 3808.5048943637426, 3834.4440056191834, 3715.7180772633396, 3801.505804245458, 3819.3888977763563, 3841.78870162141, 3855.306044911915, 3322.6843449872467, 3325.96674033259, 3341.62993172798, 3341.983290083558, 3349.832508374596, 4710.222813235812, 4728.810847566093, 4729.503402142795, 4735.266033764772, 4779.880502987418, 4313.482382114135, 4337.392765012901, 4347.2826983547675, 4356.978215108936, 4362.214801335758, 4982.60139188864, 4994.800207991676, 5012.398512178525, 5051.974740126313, 5078.898422031576, 3620.945685814715, 3641.2171383152486, 3651.7808931463933, 3655.798931058791, 3661.8718344857803, 5111.156659151273, 5204.661696989683, 5377.677339359651, 5395.974764794676, 5417.864553386157, 3620.837062332201, 3698.9445158322646, 3709.2025214578994, 3712.3317802795755, 3725.5097960815347, 3750.6811920986474, 3765.1528406108405, 3776.4712940188397, 3777.962220377816, 3788.924221515554, 4291.0, 4319.6976211665515, 4330.2638551446325, 4337.869863904088, 4346.695731298842, 5033.597312215014, 5101.362329708786, 5125.8718532036255, 5133.845984620468, 5174.7153906535505, 1265.8860203021281, 1278.0, 1278.3927634373783, 1279.8720127987312, 1287.311288460669, 827.2802661684373, 867.2914358713325, 872.2629378175432, 874.7638137702785, 875.7329014650466, 4328.343313373253, 4355.216061109031, 4360.7601581913295, 4361.563843615613, 4364.607185353333, 4989.825356112518, 5113.616478764107, 5159.58723302135, 5166.302549155891, 5189.662671926389, 4197.580241975778, 4351.390805287262, 4366.694331396771, 4391.494978077498, 4410.80151393188, 2720.408560991842, 2729.9317517061836, 2733.316670832292, 2737.5209338365707, 2749.2164733050986, 4808.855734327975, 4809.759512024385, 4831.777382404063, 4848.812041049965, 4863.392075990501, 4984.77568509418, 5098.490150984871, 5172.922406163911, 5186.507281808223, 5196.53231209193, 4745.2882067690125, 4767.73777442237, 4771.498992605796, 4776.307435421914, 4790.9281360780315, 5075.720835354091, 5077.746112694276, 5085.0, 5106.183010718267, 5125.923111153261, 4768.876385967197, 5036.874078148076, 5042.621803364689, 5043.218301163325, 5067.822626208065, 4781.760911954458, 4782.952170478251, 4788.324086569812, 4802.494179249098, 4936.198287625063, 3717.1078941054493, 3735.140917589024, 3739.8691045813266, 3742.6631603154756, 3755.2114056047444, 5214.32213812202, 5226.320578324793, 5251.238570407346, 5265.552428043659, 5278.736063196748, 4932.692836398429, 5047.697138171721, 5103.8468845934685, 5109.693418394908, 5150.913879878655, 5077.806715421799, 5085.338905942203, 5087.720175390241, 5091.261767043832, 5111.539961403491, 5004.224345226496, 5029.145045342374, 5040.168372218543, 5053.191489361684, 5061.848144555669, 4988.251762235694, 5086.160783470686, 5090.287359769562, 5132.307138536399, 5168.560672342893, 5004.524570165829, 5004.899902002013, 5013.097642424399, 5014.724190169576, 5045.571126454146], "value": 3833.2855711613124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.7196191618561, 667.8163505036117, 697.1982220446486, 707.2609123465977, 730.0217708270916, 12238.938805305972, 12995.545155919544, 13119.6720081998, 13142.277174755385, 13194.340282985848, 12978.156419832705, 13195.142315749494, 13287.0, 13322.666933326671, 13360.665983350422, 793.4406243598249, 810.1007881251805, 818.7789296889836, 819.3731795176665, 827.2843989948681, 828.7845160258316, 831.2477208126625, 831.5260301628044, 831.5343407691712, 832.8875601793741, 862.8576284913013, 864.9437786543886, 873.9949058582649, 875.5315905990296, 875.8467268228067, 863.1799790199336, 863.4517081653132, 865.9870101948444, 876.1851478125373, 879.5338470610586, 633.5058654249692, 636.5734957578387, 640.2381166411955, 642.633698791689, 643.5237923936301, 13735.656608584766, 13969.650758731113, 13972.091814032055, 13998.30008499571, 14012.929935350257, 12657.114002019862, 13002.349882505838, 13110.574543709445, 13298.40157192919, 13359.933200333937, 10111.595536178544, 10230.897691023143, 10256.94871525645, 10867.891321086767, 11629.837016298387, 3194.2653189766484, 3235.0038925169656, 3240.141362538929, 3317.568716066896, 3320.6181289151814, 60499.0, 60601.39398606046, 62050.0, 62356.034551707715, 62575.0, 10677.359358438518, 10702.304350217211, 10735.946320268506, 10775.191860610486, 10816.0, 20225.494362641053, 20239.190432382835, 20257.797422025593, 20266.493337666383, 20347.4739394546, 6362.2683391409655, 6416.935830641727, 6466.321036291121, 6476.643784591893, 6542.8364290893105, 3206.1663967368186, 3260.3805276997296, 3299.2411745298714, 3329.9667003329655, 3423.7945723256685, 3024.9243768905512, 3076.523138913472, 3102.9224269392994, 3104.425681248976, 3109.2226943264186, 508.99491005090215, 519.8251950591615, 527.8996990571779, 529.6080900133874, 536.5439376529919, 591.3081694417542, 614.4668158090977, 616.1774031667684, 630.2562975688639, 650.1580453312971, 7878.409119316074, 8295.377846661524, 8357.456765310235, 8428.873566896502, 8483.44857584255, 592.4401440638642, 621.273110460762, 629.8456878064903, 632.2728861808864, 635.9682015899186, 3976.642102210757, 4042.2521833460314, 4081.0205550667624, 4097.631213190827, 4208.452901122834, 62687.6865615669, 63791.36208637947, 64687.67656161689, 64942.0, 65312.67343663251, 54968.0, 54987.45012549823, 56575.81635305684, 58417.4158258412, 58664.413355865894, 51127.48872511302, 52423.73788131035, 52735.0, 52935.470645293826, 53525.0, 7951.244631759973, 8156.755297341089, 8262.108262108455, 8359.540225287668, 8490.660373585235, 34674.306513870084, 35485.112872178404, 35854.46218306779, 36724.265514690094, 37077.258454831295, 41018.79490602647, 44701.776491117336, 44860.551394485636, 45096.54903450923, 45664.771676141405, 10051.547680354548, 10157.187425005834, 10173.13528350069, 10218.693439196835, 10492.370457772558, 10418.843717344398, 10440.477976101316, 10528.473576321307, 10620.09729173029, 10735.570577176826, 9365.953170234105, 9575.09036641518, 9794.118529332394, 9951.850722239316, 10095.798084038424, 9776.217902567636, 10023.799524009333, 10088.848667269851, 10234.0, 10606.575736970435, 10787.460626968777, 11215.66353009411, 11847.822282665938, 12021.879781202426, 12126.636200913987, 8364.581770911553, 9146.771330716785, 9820.901790982, 9883.752906177404, 10033.598656053968, 9338.673146439842, 9568.95215523918, 9662.75843103928, 9776.315657903879, 9957.651482198089], "value": 6240.762706364585, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 117.0, 116.0, 119.0, 116.0, 116.0, 117.0, 117.0, 118.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 132.0, 135.0, 170.0, 131.0, 135.0, 130.0, 130.0, 158.0, 137.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 171.0, 172.0, 165.0, 166.0, 165.0, 166.0, 165.0, 165.0], "value": 167.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 119.0, 78.0, 78.0, 79.0, 79.0, 100.0, 78.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 108.0, 84.0, 85.0, 85.0, 99.0, 84.0, 84.0, 111.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 117.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 152.0, 152.0, 152.0, 151.0, 152.0, 152.0, 153.0, 151.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 67.0, 73.0, 68.0, 69.0, 66.0, 69.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 67.0, 66.0, 70.0, 65.0, 66.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 99.0, 88.0, 88.0, 89.0, 91.0, 86.0, 87.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 159.0, 150.0, 156.0, 148.0, 140.0, 144.0, 144.0, 200.0, 140.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 188.0, 187.0, 189.0, 192.0, 187.0, 196.0, 185.0, 187.0, 194.0], "value": 189.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 71.0, 70.0, 71.0, 77.0, 70.0, 71.0, 70.0, 69.0], "value": 72.5, "unit": "score"}]}]} 05:05:59 INFO - Return code: 0 05:05:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:59 ERROR - # TBPL SUCCESS # 05:05:59 INFO - Running post-action listener: _resource_record_post_action 05:05:59 INFO - [mozharness: 2017-02-16 13:05:59.870649Z] Finished run-tests step (success) 05:05:59 INFO - Running post-run listener: _resource_record_post_run 05:05:59 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 399851520; Read time: 8; Write time: 203208 05:05:59 INFO - TinderboxPrint: CPU usage
13.5% 05:05:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 05:05:59 INFO - TinderboxPrint: I/O write bytes / time
399,851,520 / 203,208 05:05:59 INFO - TinderboxPrint: CPU idle
8,292.0 (86.5%) 05:05:59 INFO - TinderboxPrint: CPU user
1,236.0 (12.9%) 05:05:59 INFO - TinderboxPrint: Swap in / out
0 / 0 05:05:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 100409344; Read time: 0; Write time: 56204 05:05:59 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 299028480; Read time: 0; Write time: 146980 05:06:00 INFO - Running post-run listener: _upload_blobber_files 05:06:00 INFO - Blob upload gear active. 05:06:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:00 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 05:06:00 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'] 05:06:00 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 05:06:01 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:06:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:06:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:02 INFO - (blobuploader) - INFO - Done attempting. 05:06:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:07 INFO - (blobuploader) - INFO - Done attempting. 05:06:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:06:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:06:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:08 INFO - (blobuploader) - INFO - Done attempting. 05:06:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:06:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:06:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:09 INFO - (blobuploader) - INFO - Done attempting. 05:06:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:09 INFO - Return code: 0 05:06:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb6e398e60264e0757dab2652320f3258a71a1c5bbdf03cf9cd1c1007b20ce7ed6567b5fc2d8b8ca4c7d7bfcf30fc1ec4470bdfa638e2dd896e4ae12e00180a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc1384300a76fafe3f865a2e4645af215e36e39448c0b17cecfb79f395331edf4677ffeaf39f4820ba8dfaab8a59e71363facf502aa07e5eeb50f2b46637e5cd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95f5088b869d23f02e0226f278c5873ce2ae050f7da913ebf994837dcf60b3a934d86482ab65b8d03e4582e1091b200d6e68ad4678326db59ef6df11d1574a44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f0dcaa80b9653950eb0aa28397018d5712edce35cada9d480e6cf0142450519c3cee6df64d2dccdf103f0b76711a5645cbfbefe9cc9a9240bb45e2d698a1286"} 05:06:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:09 INFO - Contents: 05:06:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb6e398e60264e0757dab2652320f3258a71a1c5bbdf03cf9cd1c1007b20ce7ed6567b5fc2d8b8ca4c7d7bfcf30fc1ec4470bdfa638e2dd896e4ae12e00180a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc1384300a76fafe3f865a2e4645af215e36e39448c0b17cecfb79f395331edf4677ffeaf39f4820ba8dfaab8a59e71363facf502aa07e5eeb50f2b46637e5cd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95f5088b869d23f02e0226f278c5873ce2ae050f7da913ebf994837dcf60b3a934d86482ab65b8d03e4582e1091b200d6e68ad4678326db59ef6df11d1574a44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f0dcaa80b9653950eb0aa28397018d5712edce35cada9d480e6cf0142450519c3cee6df64d2dccdf103f0b76711a5645cbfbefe9cc9a9240bb45e2d698a1286"} 05:06:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:09 INFO - Copying logs to upload dir... 05:06:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.055534 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-16 05:06:09.266947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 05:06:09.272851) ========= 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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb6e398e60264e0757dab2652320f3258a71a1c5bbdf03cf9cd1c1007b20ce7ed6567b5fc2d8b8ca4c7d7bfcf30fc1ec4470bdfa638e2dd896e4ae12e00180a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc1384300a76fafe3f865a2e4645af215e36e39448c0b17cecfb79f395331edf4677ffeaf39f4820ba8dfaab8a59e71363facf502aa07e5eeb50f2b46637e5cd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95f5088b869d23f02e0226f278c5873ce2ae050f7da913ebf994837dcf60b3a934d86482ab65b8d03e4582e1091b200d6e68ad4678326db59ef6df11d1574a44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f0dcaa80b9653950eb0aa28397018d5712edce35cada9d480e6cf0142450519c3cee6df64d2dccdf103f0b76711a5645cbfbefe9cc9a9240bb45e2d698a1286"} build_url:https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/Qv1FwSjTREKN2nrC3ur4wA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb6e398e60264e0757dab2652320f3258a71a1c5bbdf03cf9cd1c1007b20ce7ed6567b5fc2d8b8ca4c7d7bfcf30fc1ec4470bdfa638e2dd896e4ae12e00180a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc1384300a76fafe3f865a2e4645af215e36e39448c0b17cecfb79f395331edf4677ffeaf39f4820ba8dfaab8a59e71363facf502aa07e5eeb50f2b46637e5cd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95f5088b869d23f02e0226f278c5873ce2ae050f7da913ebf994837dcf60b3a934d86482ab65b8d03e4582e1091b200d6e68ad4678326db59ef6df11d1574a44", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f0dcaa80b9653950eb0aa28397018d5712edce35cada9d480e6cf0142450519c3cee6df64d2dccdf103f0b76711a5645cbfbefe9cc9a9240bb45e2d698a1286"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 05:06:09.301651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:06:09.301979) ========= 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=0e2a256762fed810e2f0f810000001b3-1487236069.253019-332779091 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.005399 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 05:06:09.386766) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 05:06:09.387085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 05:06:09.387375) ========= ========= Total master_lag: 0.20 =========