builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-001 starttime: 1487820358.16 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.165292) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.166316) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.167309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.182991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.183726) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 19:25:58-- 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% 112M=0s 2017-02-22 19:25:58 (112 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.190919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.392801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.393516) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014735 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.447060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.447835) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --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 c7b015c488cfb2afbcff295a9639acd85df332f8 --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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 19:25:58,544 truncating revision to first 12 chars 2017-02-22 19:25:58,544 Setting DEBUG logging. 2017-02-22 19:25:58,544 attempt 1/10 2017-02-22 19:25:58,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 19:25:59,081 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:59.442905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:59.443460) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:59.444106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:59.444588) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:59.455174) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-22 19:25:59.455704) ========= /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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:25:59 INFO - MultiFileLogger online at 20170222 19:25:59 in /builds/slave/test 19:25:59 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 19:25:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:25:59 INFO - {'append_to_log': False, 19:25:59 INFO - 'base_work_dir': '/builds/slave/test', 19:25:59 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:25:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:25:59 INFO - 'branch': 'Firefox-Non-PGO', 19:25:59 INFO - 'buildbot_json_path': 'buildprops.json', 19:25:59 INFO - 'config_files': ('talos/linux_config.py',), 19:25:59 INFO - 'default_actions': ('clobber', 19:25:59 INFO - 'read-buildbot-config', 19:25:59 INFO - 'download-and-extract', 19:25:59 INFO - 'populate-webroot', 19:25:59 INFO - 'create-virtualenv', 19:25:59 INFO - 'install', 19:25:59 INFO - 'run-tests'), 19:25:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:25:59 INFO - 'download_minidump_stackwalk': True, 19:25:59 INFO - 'download_symbols': 'ondemand', 19:25:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:25:59 INFO - 'tooltool.py': '/tools/tooltool.py', 19:25:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:25:59 INFO - '/tools/misc-python/virtualenv.py')}, 19:25:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:25:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:25:59 INFO - 'gecko_profile': False, 19:25:59 INFO - 'gecko_profile_interval': 0, 19:25:59 INFO - 'installer_path': 'installer.exe', 19:25:59 INFO - 'log_level': 'info', 19:25:59 INFO - 'log_name': 'talos', 19:25:59 INFO - 'log_to_console': True, 19:25:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:25:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:25:59 INFO - 'opt_config_files': (), 19:25:59 INFO - 'pip_index': False, 19:25:59 INFO - 'suite': 'dromaeojs-e10s', 19:25:59 INFO - 'system_bits': '32', 19:25:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:25:59 INFO - 'title': 'talos-linux64-ix-001', 19:25:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:25:59 INFO - 'use_talos_json': True, 19:25:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:25:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:25:59 INFO - 'work_dir': 'build'} 19:25:59 INFO - [mozharness: 2017-02-23 03:25:59.652978Z] Running clobber step. 19:25:59 INFO - Running pre-action listener: _resource_record_pre_action 19:25:59 INFO - Running main action method: clobber 19:25:59 INFO - rmtree: /builds/slave/test/build 19:25:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:00 INFO - Running post-action listener: _resource_record_post_action 19:26:00 INFO - [mozharness: 2017-02-23 03:26:00.566618Z] Finished clobber step (success) 19:26:00 INFO - [mozharness: 2017-02-23 03:26:00.566719Z] Running read-buildbot-config step. 19:26:00 INFO - Running pre-action listener: _resource_record_pre_action 19:26:00 INFO - Running main action method: read_buildbot_config 19:26:00 INFO - Using buildbot properties: 19:26:00 INFO - { 19:26:00 INFO - "project": "", 19:26:00 INFO - "product": "firefox", 19:26:00 INFO - "who": "kwierso@gmail.com", 19:26:00 INFO - "installer_path": "public/build/target.tar.bz2", 19:26:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:26:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:26:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 19:26:00 INFO - "stage_platform": "linux64", 19:26:00 INFO - "basedir": "/builds/slave/test", 19:26:00 INFO - "buildnumber": 715, 19:26:00 INFO - "platform": "ubuntu64_hw", 19:26:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:26:00 INFO - "slavebuilddir": "test", 19:26:00 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 19:26:00 INFO - "branch": "mozilla-central", 19:26:00 INFO - "script_repo_revision": "production", 19:26:00 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 19:26:00 INFO - "slavename": "talos-linux64-ix-001", 19:26:00 INFO - "repo_path": "mozilla-central" 19:26:00 INFO - } 19:26:00 INFO - Finding installer, test and symbols from parent task. 19:26:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:01 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 19:26:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 19:26:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 19:26:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 19:26:02 INFO - Running post-action listener: _resource_record_post_action 19:26:02 INFO - [mozharness: 2017-02-23 03:26:02.060268Z] Finished read-buildbot-config step (success) 19:26:02 INFO - [mozharness: 2017-02-23 03:26:02.060485Z] Running download-and-extract step. 19:26:02 INFO - Running pre-action listener: _resource_record_pre_action 19:26:02 INFO - Running main action method: download_and_extract 19:26:02 INFO - mkdir: /builds/slave/test/build/tests 19:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:02 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 19:26:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:26:02 INFO - Downloaded 896 bytes. 19:26:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:26:02 INFO - Using the following test package requirements: 19:26:02 INFO - {u'common': [u'target.common.tests.zip'], 19:26:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:26:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:26:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:26:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:26:02 INFO - u'mozbase': [u'target.common.tests.zip'], 19:26:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:26:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:26:02 INFO - u'web-platform': [u'target.common.tests.zip', 19:26:02 INFO - u'target.web-platform.tests.zip'], 19:26:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:26:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:26:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:02 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:05 INFO - Expected file size: 57165889 19:26:05 INFO - Obtained file size: 57165889 19:26:15 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:26:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:15 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:16 INFO - Expected file size: 57165889 19:26:16 INFO - Obtained file size: 57165889 19:26:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 19:26:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:26:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 19:26:29 INFO - Expected file size: 14404319 19:26:29 INFO - Obtained file size: 14404319 19:26:29 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')]} 19:26:29 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:26:32 INFO - Downloaded 61547669 bytes. 19:26:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:32 INFO - mkdir: /builds/slave/test/properties 19:26:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:32 INFO - Contents: 19:26:32 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - Running post-action listener: set_extra_try_arguments 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.205890Z] Finished download-and-extract step (success) 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.205986Z] Running populate-webroot step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: populate_webroot 19:26:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:32 INFO - '--tppagecycles', 19:26:32 INFO - '7'], 19:26:32 INFO - 'tests': ['tsvgm']}}, 19:26:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart', 19:26:32 INFO - 'tsvg_static']}, 19:26:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart', 19:26:32 INFO - 'tsvg_static']}, 19:26:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o']}, 19:26:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:26:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s', 19:26:32 INFO - '--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}, 19:26:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}}, 19:26:32 INFO - 'talos.zip': {'path': '', 19:26:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.219074Z] Finished populate-webroot step (success) 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.219163Z] Running create-virtualenv step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: create_virtualenv 19:26:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:32 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 19:26:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:32 INFO - Using real prefix '/usr' 19:26:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:34 INFO - Installing pip.................done. 19:26:34 INFO - Return code: 0 19:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:26:34 INFO - Reading from file tmpfile_stdout 19:26:34 INFO - Output received: 19:26:34 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) 19:26:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:34 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')]} 19:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:34 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')]} 19:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:34 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:34 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 19:26:34 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 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 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 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Downloading/unpacking psutil>=3.1.1 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:37 INFO - Installing collected packages: psutil 19:26:37 INFO - Running setup.py install for psutil 19:26:37 INFO - building 'psutil._psutil_linux' extension 19:26:37 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 19:26:37 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 19:26:37 INFO - building 'psutil._psutil_posix' extension 19:26:37 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 19:26:37 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 19:26:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:38 INFO - Successfully installed psutil 19:26:38 INFO - Cleaning up... 19:26:38 INFO - Return code: 0 19:26:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:26: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 mozsystemmonitor==0.3 19:26:38 INFO - Using env: {'DISPLAY': ':0', 19:26:38 INFO - 'HOME': '/home/cltbld', 19:26:38 INFO - 'LANG': 'en_US.UTF-8', 19:26:38 INFO - 'LANGUAGE': 'en_US:en', 19:26:38 INFO - 'LOGNAME': 'cltbld', 19:26:38 INFO - 'MAIL': '/var/mail/cltbld', 19:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:38 INFO - 'MOZ_NO_REMOTE': '1', 19:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:38 INFO - 'NO_EM_RESTART': '1', 19:26:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:38 INFO - 'PWD': '/builds/slave/test', 19:26:38 INFO - 'SHELL': '/bin/bash', 19:26:38 INFO - 'SHLVL': '1', 19:26:38 INFO - 'TERM': 'linux', 19:26:38 INFO - 'TMOUT': '86400', 19:26:38 INFO - 'USER': 'cltbld', 19:26:38 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:38 INFO - '_': '/tools/buildbot/bin/python'} 19:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:40 INFO - Installing collected packages: mozsystemmonitor 19:26:40 INFO - Running setup.py install for mozsystemmonitor 19:26:40 INFO - Successfully installed mozsystemmonitor 19:26:40 INFO - Cleaning up... 19:26:40 INFO - Return code: 0 19:26:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:40 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:40 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 19:26:40 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 19:26:40 INFO - Using env: {'DISPLAY': ':0', 19:26:40 INFO - 'HOME': '/home/cltbld', 19:26:40 INFO - 'LANG': 'en_US.UTF-8', 19:26:40 INFO - 'LANGUAGE': 'en_US:en', 19:26:40 INFO - 'LOGNAME': 'cltbld', 19:26:40 INFO - 'MAIL': '/var/mail/cltbld', 19:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:40 INFO - 'MOZ_NO_REMOTE': '1', 19:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:40 INFO - 'NO_EM_RESTART': '1', 19:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:40 INFO - 'PWD': '/builds/slave/test', 19:26:40 INFO - 'SHELL': '/bin/bash', 19:26:40 INFO - 'SHLVL': '1', 19:26:40 INFO - 'TERM': 'linux', 19:26:40 INFO - 'TMOUT': '86400', 19:26:40 INFO - 'USER': 'cltbld', 19:26:40 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:40 INFO - '_': '/tools/buildbot/bin/python'} 19:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Installing collected packages: jsonschema, functools32 19:26:43 INFO - Running setup.py install for functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Successfully installed jsonschema functools32 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', '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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:26: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 functools32==3.2.3-2 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', '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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:26: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 blobuploader==1.2.4 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:46 INFO - Downloading docopt-0.6.1.tar.gz 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:46 INFO - Running setup.py install for blobuploader 19:26:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:46 INFO - Running setup.py install for requests 19:26:46 INFO - Running setup.py install for docopt 19:26:46 INFO - Successfully installed blobuploader requests docopt 19:26:46 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:47 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 19:26:47 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 19:26:47 INFO - Using env: {'DISPLAY': ':0', 19:26:47 INFO - 'HOME': '/home/cltbld', 19:26:47 INFO - 'LANG': 'en_US.UTF-8', 19:26:47 INFO - 'LANGUAGE': 'en_US:en', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MAIL': '/var/mail/cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SHLVL': '1', 19:26:47 INFO - 'TERM': 'linux', 19:26:47 INFO - 'TMOUT': '86400', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - '_': '/tools/buildbot/bin/python'} 19:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Running setup.py (path:/tmp/pip-YcYVZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Running setup.py (path:/tmp/pip-_B6h72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Running setup.py (path:/tmp/pip-suy8Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Running setup.py (path:/tmp/pip-uguJAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Running setup.py (path:/tmp/pip-foJbtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Running setup.py (path:/tmp/pip-m8XU0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Running setup.py (path:/tmp/pip-7Caa25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Running setup.py (path:/tmp/pip-TTxOLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:47 INFO - Running setup.py (path:/tmp/pip-uKi2oT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:47 INFO - Running setup.py (path:/tmp/pip-9AMdtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Running setup.py (path:/tmp/pip-q8KGuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Running setup.py (path:/tmp/pip-hxL3IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Running setup.py (path:/tmp/pip-DMcpXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Running setup.py (path:/tmp/pip-ATOA4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Running setup.py (path:/tmp/pip-QW8QbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Running setup.py (path:/tmp/pip-tJ8Y1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Running setup.py (path:/tmp/pip-0lk2mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:48 INFO - Running setup.py install for manifestparser 19:26:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Running setup.py install for mozcrash 19:26:48 INFO - Running setup.py install for mozdebug 19:26:48 INFO - Running setup.py install for mozdevice 19:26:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Running setup.py install for mozfile 19:26:49 INFO - Running setup.py install for mozhttpd 19:26:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozinfo 19:26:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozInstall 19:26:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozleak 19:26:49 INFO - Running setup.py install for mozlog 19:26:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for moznetwork 19:26:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozprocess 19:26:49 INFO - Running setup.py install for mozprofile 19:26:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozrunner 19:26:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozscreenshot 19:26:50 INFO - Running setup.py install for moztest 19:26:50 INFO - Running setup.py install for mozversion 19:26:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:50 INFO - Cleaning up... 19:26:50 INFO - Return code: 0 19:26:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:50 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 19:26:50 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 19:26:50 INFO - Using env: {'DISPLAY': ':0', 19:26:50 INFO - 'HOME': '/home/cltbld', 19:26:50 INFO - 'LANG': 'en_US.UTF-8', 19:26:50 INFO - 'LANGUAGE': 'en_US:en', 19:26:50 INFO - 'LOGNAME': 'cltbld', 19:26:50 INFO - 'MAIL': '/var/mail/cltbld', 19:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:50 INFO - 'MOZ_NO_REMOTE': '1', 19:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:50 INFO - 'NO_EM_RESTART': '1', 19:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:50 INFO - 'PWD': '/builds/slave/test', 19:26:50 INFO - 'SHELL': '/bin/bash', 19:26:50 INFO - 'SHLVL': '1', 19:26:50 INFO - 'TERM': 'linux', 19:26:50 INFO - 'TMOUT': '86400', 19:26:50 INFO - 'USER': 'cltbld', 19:26:50 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:50 INFO - '_': '/tools/buildbot/bin/python'} 19:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 INFO - Running setup.py (path:/tmp/pip-EbPsE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 INFO - Running setup.py (path:/tmp/pip-GZTm2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 INFO - Running setup.py (path:/tmp/pip-v6Nil1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 INFO - Running setup.py (path:/tmp/pip-xB4nKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:50 INFO - Running setup.py (path:/tmp/pip-6N8PF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:50 INFO - Running setup.py (path:/tmp/pip-GutuwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 INFO - Running setup.py (path:/tmp/pip-LIFov0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 INFO - Running setup.py (path:/tmp/pip-DcKx_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:51 INFO - Running setup.py (path:/tmp/pip-PUBnxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:51 INFO - Running setup.py (path:/tmp/pip-RawthK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 INFO - Running setup.py (path:/tmp/pip-2HaMV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 INFO - Running setup.py (path:/tmp/pip-Llu4Gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 INFO - Running setup.py (path:/tmp/pip-YL53Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 INFO - Running setup.py (path:/tmp/pip-Lr69b2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 INFO - Running setup.py (path:/tmp/pip-kADf_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:51 INFO - Running setup.py (path:/tmp/pip-W4YV_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:51 INFO - Running setup.py (path:/tmp/pip-NN1b0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:53 INFO - Downloading blessings-1.6.tar.gz 19:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:53 INFO - Installing collected packages: blessings 19:26:53 INFO - Running setup.py install for blessings 19:26:54 INFO - Successfully installed blessings 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:54 INFO - Reading from file tmpfile_stdout 19:26:54 INFO - Current package versions: 19:26:54 INFO - argparse == 1.2.1 19:26:54 INFO - blessings == 1.6 19:26:54 INFO - blobuploader == 1.2.4 19:26:54 INFO - docopt == 0.6.1 19:26:54 INFO - functools32 == 3.2.3-2 19:26:54 INFO - jsonschema == 2.5.1 19:26:54 INFO - manifestparser == 1.1 19:26:54 INFO - mozInstall == 1.12 19:26:54 INFO - mozcrash == 1.0 19:26:54 INFO - mozdebug == 0.1 19:26:54 INFO - mozdevice == 0.49 19:26:54 INFO - mozfile == 1.2 19:26:54 INFO - mozhttpd == 0.7 19:26:54 INFO - mozinfo == 0.9 19:26:54 INFO - mozleak == 0.1 19:26:54 INFO - mozlog == 3.4 19:26:54 INFO - moznetwork == 0.27 19:26:54 INFO - mozprocess == 0.25 19:26:54 INFO - mozprofile == 0.28 19:26:54 INFO - mozrunner == 6.13 19:26:54 INFO - mozscreenshot == 0.1 19:26:54 INFO - mozsystemmonitor == 0.3 19:26:54 INFO - moztest == 0.8 19:26:54 INFO - mozversion == 1.4 19:26:54 INFO - psutil == 3.1.1 19:26:54 INFO - requests == 1.2.3 19:26:54 INFO - wsgiref == 0.1.2 19:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:54 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')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:54 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 19:26:54 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 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:54 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')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x19dd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, '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': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', '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 19:26:54 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 19:26:54 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 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Running post-action listener: _resource_record_post_action 19:26:54 INFO - Running post-action listener: _start_resource_monitoring 19:26:54 INFO - Starting resource monitoring. 19:26:54 INFO - [mozharness: 2017-02-23 03:26:54.785815Z] Finished create-virtualenv step (success) 19:26:54 INFO - [mozharness: 2017-02-23 03:26:54.786286Z] Running install step. 19:26:54 INFO - Running pre-action listener: _resource_record_pre_action 19:26:54 INFO - Running main action method: install 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:54 INFO - Reading from file tmpfile_stdout 19:26:54 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:55 INFO - Reading from file tmpfile_stdout 19:26:55 INFO - Output received: 19:26:55 INFO - Usage: mozinstall [options] installer 19:26:55 INFO - Options: 19:26:55 INFO - -h, --help show this help message and exit 19:26:55 INFO - -d DEST, --destination=DEST 19:26:55 INFO - Directory to install application into. [default: 19:26:55 INFO - "/builds/slave/test"] 19:26:55 INFO - --app=APP Application being installed. [default: firefox] 19:26:55 INFO - mkdir: /builds/slave/test/build/application 19:26:55 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'] 19:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:27:10 INFO - Reading from file tmpfile_stdout 19:27:10 INFO - Output received: 19:27:10 INFO - /builds/slave/test/build/application/firefox/firefox 19:27:10 INFO - Running post-action listener: _resource_record_post_action 19:27:10 INFO - [mozharness: 2017-02-23 03:27:10.512028Z] Finished install step (success) 19:27:10 INFO - [mozharness: 2017-02-23 03:27:10.512179Z] Running run-tests step. 19:27:10 INFO - Running pre-action listener: _resource_record_pre_action 19:27:10 INFO - Running main action method: run_tests 19:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:10 INFO - Python 2.7.3 19:27:10 INFO - Return code: 0 19:27:10 INFO - grabbing minidump binary from tooltool 19:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:10 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 0x1c78290>, '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 0x1c78b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c79040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:10 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 19:27:10 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 19:27:10 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 19:27:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:10 INFO - Return code: 0 19:27:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:27:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: RUST_BACKTRACE is now 1 19:27:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:27:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:10 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 19:27:10 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 19:27:10 INFO - Using env: {'DISPLAY': ':0', 19:27:10 INFO - 'HOME': '/home/cltbld', 19:27:10 INFO - 'LANG': 'en_US.UTF-8', 19:27:10 INFO - 'LANGUAGE': 'en_US:en', 19:27:10 INFO - 'LOGNAME': 'cltbld', 19:27:10 INFO - 'MAIL': '/var/mail/cltbld', 19:27:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:10 INFO - 'MOZ_NO_REMOTE': '1', 19:27:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:10 INFO - 'NO_EM_RESTART': '1', 19:27:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:10 INFO - 'PWD': '/builds/slave/test', 19:27:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:10 INFO - 'RUST_BACKTRACE': '1', 19:27:10 INFO - 'SHELL': '/bin/bash', 19:27:10 INFO - 'SHLVL': '1', 19:27:10 INFO - 'TERM': 'linux', 19:27:10 INFO - 'TMOUT': '86400', 19:27:10 INFO - 'USER': 'cltbld', 19:27:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224', 19:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:10 INFO - '_': '/tools/buildbot/bin/python'} 19:27:10 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 19:27:10 INFO - mozversion application_buildid: 20170221181700 19:27:10 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:10 INFO - mozversion application_display_name: Nightly 19:27:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:10 INFO - mozversion application_name: Firefox 19:27:10 INFO - mozversion application_remotingname: firefox 19:27:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:27:10 INFO - mozversion application_vendor: Mozilla 19:27:10 INFO - mozversion application_version: 54.0a1 19:27:10 INFO - mozversion platform_buildid: 20170221181700 19:27:10 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:27:10 INFO - mozversion platform_version: 54.0a1 19:27:10 INFO - using testdate: 1487820430 19:27:10 INFO - actual date: 1487820430 19:27:10 INFO - starting webserver on 'localhost:34408' 19:27:10 INFO - SUITE-START | Running 2 tests 19:27:10 INFO - TEST-START | dromaeo_css 19:27:10 INFO - operating with platform_type : linux_ 19:27:10 INFO - Initialising browser for dromaeo_css test... 19:27:10 INFO - TEST-INFO | started process 11521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpScCGM6/profile http://localhost:34408/getInfo.html) 19:27:13 INFO - PROCESS | 11521 | Iterating over profile /tmp/tmpScCGM6/profile 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/safebrowsing = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/gmp = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/user.js = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/key3.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/cache2 = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/cert8.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/prefs.js = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/extensions.ini = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/localstore.rdf = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/content-prefs.sqlite = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/addons.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/crashes = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/directoryLinks.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/extensions.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/frequencyCap.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/.parentlock = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/places.sqlite = Wed Feb 22 2017 19:27:14 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/permissions.sqlite = Wed Feb 22 2017 19:27:12 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/compatibility.ini = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/places.sqlite-shm = Wed Feb 22 2017 19:27:12 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/thumbnails = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/startupCache = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/extensions = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/places.sqlite-wal = Wed Feb 22 2017 19:27:14 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/blocklist.xml = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:27:14 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | Stat failure 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/minidumps = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/bookmarkbackups = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/pluginreg.dat = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 11521 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 11521 | Using date: /tmp/tmpScCGM6/profile/secmod.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 11521 | __metrics Screen width/height:1600/1200 19:27:15 INFO - PROCESS | 11521 | colorDepth:24 19:27:15 INFO - PROCESS | 11521 | Browser inner width/height: 1024/697 19:27:15 INFO - PROCESS | 11521 | __metrics 19:27:16 ERROR - PROCESS | 11521 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:27:17 INFO - TEST-INFO | 11521: exit 0 19:27:17 INFO - Browser initialized. 19:27:17 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:17 INFO - TEST-INFO | started process 11648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpScCGM6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:29 INFO - PROCESS | 11648 | RSS: Main: 153608192 19:27:29 INFO - PROCESS | 11648 | 19:30:32 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34408/tests/dromaeo/cssquery-ext.html) 19:30:34 INFO - PROCESS | 11648 | RSS: Main: 164904960 19:30:34 INFO - PROCESS | 11648 | 19:32:17 INFO - PROCESS | 11648 | ************************* 19:32:17 INFO - PROCESS | 11648 | A coding exception was thrown and uncaught in a Task. 19:32:17 INFO - PROCESS | 11648 | 19:32:17 ERROR - PROCESS | 11648 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:32:17 INFO - PROCESS | 11648 | Full stack: 19:32:17 INFO - PROCESS | 11648 | ************************* 19:33:36 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-ext.html (next: http://localhost:34408/tests/dromaeo/cssquery-jquery.html) 19:33:37 INFO - PROCESS | 11648 | RSS: Main: 157630464 19:33:37 INFO - PROCESS | 11648 | 19:36:39 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34408/tests/dromaeo/cssquery-mootools.html) 19:36:40 INFO - PROCESS | 11648 | RSS: Main: 165752832 19:36:40 INFO - PROCESS | 11648 | 19:39:42 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34408/tests/dromaeo/cssquery-prototype.html) 19:39:42 INFO - PROCESS | 11648 | RSS: Main: 158818304 19:39:42 INFO - PROCESS | 11648 | 19:42:45 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34408/tests/dromaeo/cssquery-yui.html) 19:42:46 INFO - PROCESS | 11648 | RSS: Main: 165519360 19:42:46 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:34408/tests/dromaeo/cssquery-yui.html (next: http://localhost:34408/tests/dromaeo/cssquery-dojo.html) 19:45:49 INFO - PROCESS | 11648 | RSS: Main: 159109120 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | __start_tp_report 19:45:49 INFO - PROCESS | 11648 | _x_x_mozilla_page_load 19:45:49 INFO - PROCESS | 11648 | _x_x_mozilla_page_load_details 19:45:49 INFO - PROCESS | 11648 | |i|pagename|runs| 19:45:49 INFO - PROCESS | 11648 | |0;dojo.html;2515.484325713229;2538.987305063474;2541.4917016596687;2553.6680231569894;2556.8338058026216;4356.695031347807;4504.166729155108;4504.256797628392;4582.679212455126;4622.884427889305;4399.846005389812;4514.119746649405;4527.547245275471;4565.223911934971;4583.404157459534;4147.999999999993;4215.494140703105;4218.388333691613;4272.2950713132295;4279.358096285551;4240.194363071022;4256.914861702764;4288.228118938606;4289.056407590325;4330.39374487572;4107.9178416431805;4133.21468920904;4164.7709375984305;4296.785160741982;4308.569143085698;4140.399642051885;4162.542120366757;4172.937405938914;4209.116085622023;4215.241256573816;4140.627343539066;4145.97927010366;4149.668026557868;4185.393117997874;4190;4411.647068234533;4420;4430.379746835446;4508.053308805155;4512.210363186462;4847.175980083394;4867.853964381075;4868.853934381974;4873.707577545359;4950.702957822542;4862.878428039292;4922.483139270396;4930.161872481687;4931.5258674546885;4976.7013979161;4403.9779801100785;4622.052479241781;4626.768661566942;4627.5603817637275;4663.743494107856;4781.139394908881;4918.040982008545;4938.456769755324;4955.826546070943;4975.0547395994645;4605.700629459075;4644.419447569054;4778.283257511401;4786.114568804782;4789.449213340441;4895.290182923456;4898.485659005824;4899.216125419915;4936.185529387611;4947.5794557462295;4599.9080018399445;4834.226523756252;4865.805367785249;4867.075255701406;4869.123557759566;4618.376519169869;4805.183118869801;4841.2496063110275;4852.611791056707;4912.877178070506;4140.60664236897;4148.709590328708;4156.854510092132;4179.519355274121;4188.371744238389;3629.129009037812;3644.3804553226014;3646.7811931283677;3658.048907284072;3682.226732386175;4079.3676980068135;4114.897127571835;4195.916081678411;4208.305629571087;4221.746695198237;4840.6127509799135;4864.78108485119;4865.513448655106;4903.5586797188425;4917.237828136645;4221.852235171816;4225.281702110648;4235.7035007549175;4236.406903033578;4274.700770946004;4208.494980602347;4217.894552636209;4347.195768782785;4382.342648602735;4403.779811009436;4582.656300777388;4595;4610.654200934876;4636.489986101458;4642.7446490442;4895.510448955076;4901.50984901507;4915.361003069578;4919.409670839522;4923.822817885104;4503.099380124054;4553.931691024571;4571.839985600488;4596.862094137181;4631.58315751584;4783.210770223013;4791.952080479151;4793.448753393404;4802.855914322576;4805.783739731791;4851.272309153655;4854.126257273725;4875.219964805614;4878.682885612424;4911.7298548580175;4561.429821272341;4579.771011449481;4712.575868171881;4734.384530011076;4769.093872164278;4740.905181896412;4763.118823017753;4775.068861572099;4806.423229212517;4810.975945120252;4671.252599584051;4684.297355396581;4706.717596944194;4709.599684026896;4765.9046819064115;4564.566366195206;4643.512431194676;4740.265258884879;4748.881277968078;4802.8799280018275;4682.929756053593;4700.55344742249;4700.811967521261;4739.691920025188;4740.597049250852 19:45:49 INFO - PROCESS | 11648 | |1;ext.html;525.2987262005223;533.3386600615236;535.7508758427332;549.8020712543483;581.9883602327956;37035.629643703556;39529.80235098824;39532;39677.60322396775;39694.81678507681;40068.799656001676;40077.599224007754;40366.79816600913;40374.7981260094;40423.79788101063;7692.769216923487;7842.960785196066;7855.214478552128;7863.37093032556;9269.907300927005;9419.811603767921;9542.379745316566;9553.474558899257;9556.856647150298;9557.474338911357;9455.479948602824;9512.33413661044;9585.520723963775;9591.856122158173;9620.759481012961;9400;9478.715638530855;9484.762880927998;9508.762280942963;9578.760530986781;9329.067093290616;9392.718218453458;9407.388519746195;9415.576299066559;9426;31544.211394715086;31754.523682144787;32003.679963200302;32143.678563214304;32163.03510894677;3282.770206085575;3398.9373345803924;3422.760406771527;3426.623071462137;3463.705585025276;25092.874535627387;25205.87397063021;25248.873755631288;25476.490470190678;25807;3412.1128506588466;3516.4000379827116;3519.4720791881423;3535.1692352297446;3608.332458495187;227495;227799;228236;229462;229679;41767.582324176976;42946;46288.30567541489;46663.76668116706;46715.29927051097;49160.50839491631;49849;50052.74973625181;53754.73122634362;54075;12133.575324863587;12301.384930753606;12377.814332785016;12390.442430090667;12442;9370.95314523423;9401.341906066646;9406.200472959876;9421;9445.291603129794;15467.066616672915;15491.380344786308;15553.066815991077;15626.374945002073;15744.68510629781;6411;6594.571352862049;6595.472362211011;6612.603243805387;6669.533132680665;730.6602429870136;747.5552046532305;751.8796992481286;758.5145506875604;766.2835249042053;25929.22212333633;26643.200703978913;27076.59385109225;27223;27430.314242143708;878.547548012774;910.6084383715066;913.1370854542394;931.2084727981162;947.4220725357526;13553.932230338785;13908.944745698458;13952.211753433432;14088.788668169986;14182;116049;116335.41832290785;116373;116481.41759291149;116507;47582.28626570604;47915.281270780964;47916.28125578119;48069;48145;1112.593903225317;1152.982705259422;1177.9705507362385;1177.9764404711857;1180.0795379603924;25294.367640808392;25318.873405632854;26036.73963260419;26360.736392635827;26519;32742.34515309728;32900.67099329072;33048.50427243545;33054;33095.50356744602;212407;212710;213140;214073;214120;22434;23331.533369332858;23351.532969340857;24015.279541613778;24587;35510.82244588761;35541.1114722134;35805.64194358023;36297;36442.27115457729;30034.699653004063;30213.546796797626;30733.692663073976;31323.373532528767;31928.36143277075;30057.849710751307;30394;30545.694543054284;30753.38493230078;31586.368272633958;29270.853645731637;29900.252493686963;30269.243268918453;30405.695943040286;30970;26177.73822261753;27296.317592060357;27861.58207626928;27913.860430697718;28027.57958630663;21457.892710536347;21908;21945.122195112017;22122.55754884861;22132.55734885261 19:45:49 INFO - PROCESS | 11648 | |2;jquery.html;389.1419420178505;389.7895136626222;402.4968789013732;408.1775222925805;428.188582635905;12392.566260180896;12532.561360352389;16573.00561966283;17970.28118875245;17988.37040703574;14121.92939035306;17134.14329283537;17488.65022699545;17564.473065808015;17657.20542575584;5076.340075790152;5245.344331958505;5251.923355712089;5275.340582427197;5310.07073762092;5106.340439357224;5197.298364720752;5203.323567936163;5288.206768984644;5305.0450918834595;5237.633365664405;5262.500062494076;5268.604854635899;5268.841934741945;5281.366236051679;5098.107831129538;5148.613853960968;5188.92216616751;5201.193814958688;5226.7909283628605;4657.254839225741;4664.86005419838;4711.057788442326;4714.292856071616;4724.881877953045;19440.611187776307;20524.79475205244;21796.673049904264;21839.890800545894;21975.23086691974;2061.0184263564747;2150.290404166621;2176.5320456101854;2192.6382146945734;2218.5673793610254;9427.622895084189;9775.31572789905;9872.308938374317;12010.159288849783;12584.559540416041;1640.1717132847955;1683.6194320656944;1685.2837544043855;1686.4434736536914;1726.0506721303234;68635.31364686339;73559.26440735577;76083.23916760873;76530;82230;9098.590563424661;9617.56720947559;9643.517824108907;10760.246782725135;11199.944000279946;12884.291363974884;12916.870831291755;12923.15999460032;12952.417141228667;13029.47882084706;6623.138991931018;6780.864382712318;6820.931790682129;6926.757563485254;6990.370866622028;5865.706714664249;6048.516118710492;6134.754609815557;6152.569320147636;6173.382661733881;3955.841766329372;3956.742811717229;3981.5222173339384;4012.879613611596;4111.157212771374;1924.6920492721097;1944.2903340280695;1947.6981067934494;1972.556174860645;1997.5106099005836;762.5348537392186;793.5159552672865;797.5453991225062;798.9081255655674;809.3080416244144;17411.47765567035;17412.738808917875;17733.7339939901;18105.275788968294;18604.81395186058;782.7612578163714;794.5471081483499;795.1253621016835;802.5345299726145;818.7134502923928;9470.810583788423;9663.661771837951;10007.199424046059;10021.549030293509;10157.441340726331;342037;347469;350470;497440;501233;87822.56088719516;92745.5362723182;94080.52959735157;94902;96257.51871240599;945.2721404518483;955.5174636808367;986.5067466266867;986.8766404199619;987.3384832162468;15687.215639218222;16067.839321606634;16803.579910501845;16903.32386704518;17101.23044462977;39610.40584391179;40017.399739003355;40345.1930961385;40438.32447577535;41976.58023419727;54743;55169.44830551804;55461.11772175909;55623.72188139033;57447.71276143593;8025.23760242776;8130.471519351223;8384.287335576544;8432.494050356998;8691.217790398894;9204.447733136034;11779.941100294443;12138.635840924786;12480;12494.750104998031;5732.684702341245;9009.864852027094;10019.54912028975;10529.631462898762;10689.30519516229;10550.366977981346;10698.411587362458;10745.355278683304;10975.451227438436;11002.614908478165;10247.84628230592;10271.332363396356;10464.162866970471;10514.57941682324;10605.151587873106;7765.572893490912;7781;7943.84112317762;7958.323542498721;8071.475354102199;9495.430274183294;9944.900550994687;10608.787824243318;10755.085817705583;10841.783164336826 19:45:49 INFO - PROCESS | 11648 | |3;mootools.html;1300.9804852927207;1315.1057281048888;1316.433933408634;1322.8346456692918;1330.88022078013;7028.824279393018;7196.10048743907;7382.7785166444955;7415.369693576045;7427.25727427257;7226.891596626055;7253.963730181355;7278.272172782719;7323.377512911409;7328.083989501312;2051.1077681208685;2097.7063211150453;2101.5271563898154;2106.5681535285307;2112.6725357569526;2100.8550340064703;2124.4582631428966;2126.5853158634072;2128.6913397557346;2137.2092325839385;2020.717099606049;2063.0819285417997;2071.637463443891;2083.197968782022;2083.9791602083938;2008.4476768888599;2033.3391647714477;2045.447729113146;2055.9588808223825;2058.9485262868398;1760.9207585658676;1804.4857215693464;2023.838092952575;2038.8368930485526;2131.872087674729;7060.541064830821;7278.563286202845;7283.308085731847;7323.7070517179245;7394.445416593777;4303.806328715215;4470.351798989161;4470.932936005963;4480.103979204139;4480.551944805494;2822.1815673454676;2839.0914907229694;2882.322654176267;2902.6516817981765;2917.154025332651;1077.9353238805618;1121.556984990924;1152.7406333574963;1159.7796418680507;1161.6340852631395;9782.755431114208;9804.852927206097;9817.754556136082;9917.454540000297;9927.404355738607;3747.5690295616355;3830.751001184914;3973;4052.8986775330854;4065.3495440729935;7713.305802477804;7723.575203363869;7730.11103723079;7735.187805280477;7736.032995875516;4166.104287578185;4182.665386769051;4187.748735075906;4191.559886211965;4202.579742025773;3844.442555829445;4012.8394864205106;4053.5338436079637;4065.654419374327;4084.7140700150703;3102.255458689898;3136.717695407379;3222.5971753530807;3225.645179030305;3227.0802821196316;953.8235426445991;954.9092836180552;960.0735290444754;972.4700038478904;972.6401231544373;736.6537727268176;755.7883792538098;767.9808004799925;774.8217909880647;782.6047845837816;4639.698419602782;4786.731516148154;4808.06242782654;5006.07387633289;5074.213496907985;854.8418542569644;884.5311984648147;889.1730690457936;897.8832751742232;921.8893732751978;3270.7219886309454;3314.7016768490953;3316.7014968652456;3354.530365748797;3484.651534846495;8491.19333663301;8570.614322355385;8765.474071555727;8910.331725120643;8969.596368163448;9034.096590341045;9040.276777857756;9075.13786190311;9088.500132492776;9094.86357704635;6607.504437167232;6744.258131605519;6758.560693554805;6763.323667633197;6789.219239787488;4756.173687233112;4760.880977975578;4896.951030489649;4947.6536642434685;5143.2285157225215;7785.377169826287;7840.960795195988;7864.095629002738;7874.960625196837;8071.71748988806;7779.144294127528;8024.438289319692;8095.757127286191;8096.311813495683;8136.877946830684;6304.338044505261;6417.1657684500715;6444.355564443518;6491.448226900767;6518.380753828568;6900.654967251517;6920.100386949664;6944.895826562707;6983.755568555076;7028.718851246097;6205.6586887721605;6242.094896240111;6247.718852651732;6288.591241569284;6430.164078669743;6264.404881536247;6278.309385967448;6313.652749098844;6340.714667839866;6476.287608362982;5369.630744160214;5395.919061214006;5443.8094666686475;5445.673259604437;5457.426970168234;4418.160549495586;4439.089986552814;4454.220511410425;4494.303382975644;4507.323901414814;4250.447441832542;4333.826646934087;4463.04044630406;4476.552344765498;4560 19:45:49 INFO - PROCESS | 11648 | |4;prototype.html;347.34005389759454;348.9877854275098;364.46423757077093;364.5406787447813;366.4942379516267;4922.261660750889;4951.504849515051;4953.232249001401;4968.3292755478;4973.403191617011;4839.733814640203;4968.627352948534;4991.875203119924;4999.8000079996755;5018.899622007558;3908.6482216600502;4013.1973605278913;4031.9395209071886;4039.919201615973;4042.110735638155;4025.5773143819915;4033.7579745215235;4047.8583249586272;4070.837166513336;4078.062045729469;4020.959790402088;4038.7778672173017;4050.230456213325;4060.2082593894056;4060.8629583716606;3915;4035.717499775017;4052.9999999999854;4053.8581149659767;4067.959320406788;3608.981955090234;3624.4382120771324;3636.472711456824;3658.9451158232646;3659.9817000914827;4874.65877388583;4916.680415772963;4923.163062279421;4939.827106051307;4950.183219768734;4579.335996280521;4607.4471063472265;4612.308153776927;4614.30785382192;4644.976775116136;4978.77595508203;5235.842924712265;5246.842594722164;5252.6323157379;5305.124654432014;4017.4375587417785;4027.8388864445387;4032.697547683935;4033.4151548025666;4037.253108174997;5236.293100431431;5374.247605335257;5512.173174023888;5532.336119665626;5584.385717571064;3994.0613955720382;4088.8977775555254;4096.078382363944;4112.49893788765;4130.215259100762;4150.190712810973;4173.686973477002;4175.206710724952;4175.436316097349;4188.308929026676;4425.7829096998585;4607.470140933836;4607.792649330788;4623.237165867594;4640.257558790578;5196.922046169235;5227.242049902743;5245.659032162897;5251.868703282448;5300.549453296436;1373.7046534995025;1379.2138481065697;1396.804447377368;1397.8462369139386;1398.3078376203641;960.011188475871;1005.797303904447;1009.3792317724693;1011.5700827148432;1016.4905115902878;4455.1312494064;4471.396361491222;4475.373447717322;4481.103779244099;4481.731096134243;5155.871103222449;5322.946770532322;5330.866728331823;5340.439253878287;5343.973280133574;4300;4468.865934021985;4491.967921652871;4492.550744925481;4520.118576877478;2774.847383393933;2787.4007088476073;2796.6084748135277;2810.552565428806;2811.2550174203852;4896.216605343128;4905.460399356068;4920.852374428773;4921.3356196912955;4921.434035086011;5149.665271757323;5321.281626980308;5360.91958620621;5402.6758394496455;5486.506214440719;4976.552110310089;5018.924716129261;5021.573166280834;5035.219540971156;5066.771995260148;5350.892982140414;5422.539084177889;5440.836774896759;5443.965646527147;5452.055889819611;5187.221916712523;5315.3090098286975;5322.920156197738;5350.555350055541;5386.811461598982;4795.20879054952;4902.3872015998;4959.20652695567;5024.145895197752;5041.269015992587;3792.5259342582176;3825.8087095645665;3832.48261484696;3844.788536630512;3856.9228615428096;5436.592255580768;5447;5447.428020057995;5454.727263636882;5470.64440811346;4940.344984430256;5245;5249.737513124405;5306.708131052829;5323.201519772065;5237.554807841224;5244.875825290764;5248.790048398174;5266.262723218676;5282.6038047147385;5192.299039629602;5195.896082078261;5196.454372290855;5211.530962213419;5224.440024388098;4961.50384961501;5241.31862857826;5264.15773476242;5273.472652734696;5285.814996475105;5120.74396280177;5132.46109158533;5136.1268584340005;5137.563307118937;5145.3568303962 19:45:49 INFO - PROCESS | 11648 | |5;yui.html;643.1799455693993;662.5229834519145;723.7901008707476;727.7307396263383;760.8668483015473;11974.281543107409;13021.414036368362;13033.283169425702;13116.081874268804;13122.343882805857;12897.355132243398;12956.352182390867;13044.73910521789;13063.73872522549;13069.869301306984;780.9648565814524;792.9167437419075;799.1528979281951;803.1968031968032;807.4993504027511;794.364508393283;801.4550105927966;802.1176705623797;802.8394321135767;805.4643661964786;818.9222023907749;820.6553247635977;821.2321479416735;823.798169448483;826.8470332988386;813.9267465928066;819.6024927909959;823.8187598728271;825.2572684583893;826.0500424511799;606.7391021860598;609.05596325695;609.6768712582339;610.6000569626901;612.0849330251258;13055.347232638425;13208.339583020907;13329.266890321029;13334.066615336931;13461.932690336584;12668;13101.47594096235;13121.606351809462;13369.064165508418;13419.66450838727;9899.554520046615;10031.59873605055;10035.247356448295;10196.847047294297;10296.588136474533;3256.2022304535303;3258.837058147083;3275.819829909354;3275.901722948315;3370.3427831572853;62464;63095.684521577554;63532.36467635357;63784;64294.67852660754;9620.230381569458;9704.563294651758;9735.756606084762;9828.361156524803;9929.702108936743;18413.079346032646;18420.07899605041;18548.350807721665;18557.16492757829;18629.161687724085;6301.936980630135;6458.773942911976;6468.967655161789;6490.350964903472;6550;3297.20866991925;3439.7420193485586;3460.0311912664492;3470.323286959019;3487.250241198104;3024.259056531164;3097.4889143291557;3107.409592177479;3135.623725152996;3162.367526494664;491.8229437402532;492.5493173746054;494.1771949703775;494.70317809314844;507.07711964225496;524.8897731476357;532.2335836395578;538.5018857556744;547.4826289156788;549.85978575463;7438.516496427715;7700.807479813049;8084.353251739847;8115.107338192912;8171.959140204261;443.3040127000643;445.46766613896085;460.16480088639014;495.15081634996153;498.38449514849134;3465.601455832561;3622.565292164957;3762.830672619684;3770.7549009314307;3936.252112098692;46641;49650;56158;58757;60476.39523604708;52210.73894630503;52308.47691523112;52826.207606885924;53600.19599706007;53606.73196633992;46749;47094.29358559624;47492.76253618779;47807.282890756665;48792.51207487951;7416.25837416254;7627.5042122261875;7734.8839767402405;7819.726309579138;7829.29536341732;33218;33243.33513329768;33631.495527566614;34352;34990.47514287237;39511.60488395079;41233.58766412418;41894;42337.15325693407;43741.25034998565;8782.429142105742;9052.773680658036;9087.59105840252;9239.815203695754;9281.071892810665;9646.951765241129;9707.805843882941;9734.902650973683;9791.412515249107;9865.802683946424;9616.51917404113;9821;10109.140723038625;10115.494225288854;10320.225983051452;9090.54547272647;9236.214921731727;9482.857757133786;9499.43003419797;9629.807403852024;8037.959810201145;8243.83512329762;8273.958630206811;8422.620982055945;8653.956730216309;8114.918850811652;8163.79590510242;8424.452410593292;8494.575271236537;8568.271696905833;7975.322097621767;8025.117237104031;8124.350051995944;8294.419390642597;8343.916560834557 19:45:49 INFO - PROCESS | 11648 | __end_tp_report 19:45:49 INFO - PROCESS | 11648 | __start_cc_report 19:45:49 INFO - PROCESS | 11648 | _x_x_mozilla_cycle_collect,376 19:45:49 INFO - PROCESS | 11648 | __end_cc_report 19:45:49 INFO - PROCESS | 11648 | __startTimestamp1487821549550__endTimestamp 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | ------- Summary: start ------- 19:45:49 INFO - PROCESS | 11648 | Number of tests: 6 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#0] dojo.html Cycles:165 Average:4496.53 Median:4618.38 stddev:466.06 (10.1%) stddev-sans-first:440.81 19:45:49 INFO - PROCESS | 11648 | Values: 2515.5 2539.0 2541.5 2553.7 2556.8 4356.7 4504.2 4504.3 4582.7 4622.9 4399.8 4514.1 4527.5 4565.2 4583.4 4148.0 4215.5 4218.4 4272.3 4279.4 4240.2 4256.9 4288.2 4289.1 4330.4 4107.9 4133.2 4164.8 4296.8 4308.6 4140.4 4162.5 4172.9 4209.1 4215.2 4140.6 4146.0 4149.7 4185.4 4190.0 4411.6 4420.0 4430.4 4508.1 4512.2 4847.2 4867.9 4868.9 4873.7 4950.7 4862.9 4922.5 4930.2 4931.5 4976.7 4404.0 4622.1 4626.8 4627.6 4663.7 4781.1 4918.0 4938.5 4955.8 4975.1 4605.7 4644.4 4778.3 4786.1 4789.4 4895.3 4898.5 4899.2 4936.2 4947.6 4599.9 4834.2 4865.8 4867.1 4869.1 4618.4 4805.2 4841.2 4852.6 4912.9 4140.6 4148.7 4156.9 4179.5 4188.4 3629.1 3644.4 3646.8 3658.0 3682.2 4079.4 4114.9 4195.9 4208.3 4221.7 4840.6 4864.8 4865.5 4903.6 4917.2 4221.9 4225.3 4235.7 4236.4 4274.7 4208.5 4217.9 4347.2 4382.3 4403.8 4582.7 4595.0 4610.7 4636.5 4642.7 4895.5 4901.5 4915.4 4919.4 4923.8 4503.1 4553.9 4571.8 4596.9 4631.6 4783.2 4792.0 4793.4 4802.9 4805.8 4851.3 4854.1 4875.2 4878.7 4911.7 4561.4 4579.8 4712.6 4734.4 4769.1 4740.9 4763.1 4775.1 4806.4 4811.0 4671.3 4684.3 4706.7 4709.6 4765.9 4564.6 4643.5 4740.3 4748.9 4802.9 4682.9 4700.6 4700.8 4739.7 4740.6 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#1] ext.html Cycles:180 Average:34428.57 Median:18247.22 stddev:50110.57 (274.6%) stddev-sans-first:50186.48 19:45:49 INFO - PROCESS | 11648 | Values: 525.3 533.3 535.8 549.8 582.0 37035.6 39529.8 39532.0 39677.6 39694.8 40068.8 40077.6 40366.8 40374.8 40423.8 7692.8 7843.0 7855.2 7863.4 9269.9 9419.8 9542.4 9553.5 9556.9 9557.5 9455.5 9512.3 9585.5 9591.9 9620.8 9400.0 9478.7 9484.8 9508.8 9578.8 9329.1 9392.7 9407.4 9415.6 9426.0 31544.2 31754.5 32003.7 32143.7 32163.0 3282.8 3398.9 3422.8 3426.6 3463.7 25092.9 25205.9 25248.9 25476.5 25807.0 3412.1 3516.4 3519.5 3535.2 3608.3 227495.0 227799.0 228236.0 229462.0 229679.0 41767.6 42946.0 46288.3 46663.8 46715.3 49160.5 49849.0 50052.7 53754.7 54075.0 12133.6 12301.4 12377.8 12390.4 12442.0 9371.0 9401.3 9406.2 9421.0 9445.3 15467.1 15491.4 15553.1 15626.4 15744.7 6411.0 6594.6 6595.5 6612.6 6669.5 730.7 747.6 751.9 758.5 766.3 25929.2 26643.2 27076.6 27223.0 27430.3 878.5 910.6 913.1 931.2 947.4 13553.9 13908.9 13952.2 14088.8 14182.0 116049.0 116335.4 116373.0 116481.4 116507.0 47582.3 47915.3 47916.3 48069.0 48145.0 1112.6 1153.0 1178.0 1178.0 1180.1 25294.4 25318.9 26036.7 26360.7 26519.0 32742.3 32900.7 33048.5 33054.0 33095.5 212407.0 212710.0 213140.0 214073.0 214120.0 22434.0 23331.5 23351.5 24015.3 24587.0 35510.8 35541.1 35805.6 36297.0 36442.3 30034.7 30213.5 30733.7 31323.4 31928.4 30057.8 30394.0 30545.7 30753.4 31586.4 29270.9 29900.3 30269.2 30405.7 30970.0 26177.7 27296.3 27861.6 27913.9 28027.6 21457.9 21908.0 21945.1 22122.6 22132.6 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#2] jquery.html Cycles:180 Average:25658.06 Median:4661.06 stddev:68964.18 (1479.6%) stddev-sans-first:69131.54 19:45:49 INFO - PROCESS | 11648 | Values: 389.1 389.8 402.5 408.2 428.2 12392.6 12532.6 16573.0 17970.3 17988.4 14121.9 17134.1 17488.7 17564.5 17657.2 5076.3 5245.3 5251.9 5275.3 5310.1 5106.3 5197.3 5203.3 5288.2 5305.0 5237.6 5262.5 5268.6 5268.8 5281.4 5098.1 5148.6 5188.9 5201.2 5226.8 4657.3 4664.9 4711.1 4714.3 4724.9 19440.6 20524.8 21796.7 21839.9 21975.2 2061.0 2150.3 2176.5 2192.6 2218.6 9427.6 9775.3 9872.3 12010.2 12584.6 1640.2 1683.6 1685.3 1686.4 1726.1 68635.3 73559.3 76083.2 76530.0 82230.0 9098.6 9617.6 9643.5 10760.2 11199.9 12884.3 12916.9 12923.2 12952.4 13029.5 6623.1 6780.9 6820.9 6926.8 6990.4 5865.7 6048.5 6134.8 6152.6 6173.4 3955.8 3956.7 3981.5 4012.9 4111.2 1924.7 1944.3 1947.7 1972.6 1997.5 762.5 793.5 797.5 798.9 809.3 17411.5 17412.7 17733.7 18105.3 18604.8 782.8 794.5 795.1 802.5 818.7 9470.8 9663.7 10007.2 10021.5 10157.4 342037.0 347469.0 350470.0 497440.0 501233.0 87822.6 92745.5 94080.5 94902.0 96257.5 945.3 955.5 986.5 986.9 987.3 15687.2 16067.8 16803.6 16903.3 17101.2 39610.4 40017.4 40345.2 40438.3 41976.6 54743.0 55169.4 55461.1 55623.7 57447.7 8025.2 8130.5 8384.3 8432.5 8691.2 9204.4 11779.9 12138.6 12480.0 12494.8 5732.7 9009.9 10019.5 10529.6 10689.3 10550.4 10698.4 10745.4 10975.5 11002.6 10247.8 10271.3 10464.2 10514.6 10605.2 7765.6 7781.0 7943.8 7958.3 8071.5 9495.4 9944.9 10608.8 10755.1 10841.8 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#3] mootools.html Cycles:180 Average:4757.40 Median:5382.77 stddev:2602.87 (48.4%) stddev-sans-first:2597.21 19:45:49 INFO - PROCESS | 11648 | Values: 1301.0 1315.1 1316.4 1322.8 1330.9 7028.8 7196.1 7382.8 7415.4 7427.3 7226.9 7254.0 7278.3 7323.4 7328.1 2051.1 2097.7 2101.5 2106.6 2112.7 2100.9 2124.5 2126.6 2128.7 2137.2 2020.7 2063.1 2071.6 2083.2 2084.0 2008.4 2033.3 2045.4 2056.0 2058.9 1760.9 1804.5 2023.8 2038.8 2131.9 7060.5 7278.6 7283.3 7323.7 7394.4 4303.8 4470.4 4470.9 4480.1 4480.6 2822.2 2839.1 2882.3 2902.7 2917.2 1077.9 1121.6 1152.7 1159.8 1161.6 9782.8 9804.9 9817.8 9917.5 9927.4 3747.6 3830.8 3973.0 4052.9 4065.3 7713.3 7723.6 7730.1 7735.2 7736.0 4166.1 4182.7 4187.7 4191.6 4202.6 3844.4 4012.8 4053.5 4065.7 4084.7 3102.3 3136.7 3222.6 3225.6 3227.1 953.8 954.9 960.1 972.5 972.6 736.7 755.8 768.0 774.8 782.6 4639.7 4786.7 4808.1 5006.1 5074.2 854.8 884.5 889.2 897.9 921.9 3270.7 3314.7 3316.7 3354.5 3484.7 8491.2 8570.6 8765.5 8910.3 8969.6 9034.1 9040.3 9075.1 9088.5 9094.9 6607.5 6744.3 6758.6 6763.3 6789.2 4756.2 4760.9 4897.0 4947.7 5143.2 7785.4 7841.0 7864.1 7875.0 8071.7 7779.1 8024.4 8095.8 8096.3 8136.9 6304.3 6417.2 6444.4 6491.4 6518.4 6900.7 6920.1 6944.9 6983.8 7028.7 6205.7 6242.1 6247.7 6288.6 6430.2 6264.4 6278.3 6313.7 6340.7 6476.3 5369.6 5395.9 5443.8 5445.7 5457.4 4418.2 4439.1 4454.2 4494.3 4507.3 4250.4 4333.8 4463.0 4476.6 4560.0 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#4] prototype.html Cycles:180 Average:4389.08 Median:4918.77 stddev:1224.56 (24.9%) stddev-sans-first:1189.82 19:45:49 INFO - PROCESS | 11648 | Values: 347.3 349.0 364.5 364.5 366.5 4922.3 4951.5 4953.2 4968.3 4973.4 4839.7 4968.6 4991.9 4999.8 5018.9 3908.6 4013.2 4031.9 4039.9 4042.1 4025.6 4033.8 4047.9 4070.8 4078.1 4021.0 4038.8 4050.2 4060.2 4060.9 3915.0 4035.7 4053.0 4053.9 4068.0 3609.0 3624.4 3636.5 3658.9 3660.0 4874.7 4916.7 4923.2 4939.8 4950.2 4579.3 4607.4 4612.3 4614.3 4645.0 4978.8 5235.8 5246.8 5252.6 5305.1 4017.4 4027.8 4032.7 4033.4 4037.3 5236.3 5374.2 5512.2 5532.3 5584.4 3994.1 4088.9 4096.1 4112.5 4130.2 4150.2 4173.7 4175.2 4175.4 4188.3 4425.8 4607.5 4607.8 4623.2 4640.3 5196.9 5227.2 5245.7 5251.9 5300.5 1373.7 1379.2 1396.8 1397.8 1398.3 960.0 1005.8 1009.4 1011.6 1016.5 4455.1 4471.4 4475.4 4481.1 4481.7 5155.9 5322.9 5330.9 5340.4 5344.0 4300.0 4468.9 4492.0 4492.6 4520.1 2774.8 2787.4 2796.6 2810.6 2811.3 4896.2 4905.5 4920.9 4921.3 4921.4 5149.7 5321.3 5360.9 5402.7 5486.5 4976.6 5018.9 5021.6 5035.2 5066.8 5350.9 5422.5 5440.8 5444.0 5452.1 5187.2 5315.3 5322.9 5350.6 5386.8 4795.2 4902.4 4959.2 5024.1 5041.3 3792.5 3825.8 3832.5 3844.8 3856.9 5436.6 5447.0 5447.4 5454.7 5470.6 4940.3 5245.0 5249.7 5306.7 5323.2 5237.6 5244.9 5248.8 5266.3 5282.6 5192.3 5195.9 5196.5 5211.5 5224.4 4961.5 5241.3 5264.2 5273.5 5285.8 5120.7 5132.5 5136.1 5137.6 5145.4 19:45:49 INFO - PROCESS | 11648 | 19:45:49 INFO - PROCESS | 11648 | [#5] yui.html Cycles:180 Average:13589.77 Median:59616.70 stddev:16981.14 (28.5%) stddev-sans-first:17000.95 19:45:49 INFO - PROCESS | 11648 | Values: 643.2 662.5 723.8 727.7 760.9 11974.3 13021.4 13033.3 13116.1 13122.3 12897.4 12956.4 13044.7 13063.7 13069.9 781.0 792.9 799.2 803.2 807.5 794.4 801.5 802.1 802.8 805.5 818.9 820.7 821.2 823.8 826.8 813.9 819.6 823.8 825.3 826.1 606.7 609.1 609.7 610.6 612.1 13055.3 13208.3 13329.3 13334.1 13461.9 12668.0 13101.5 13121.6 13369.1 13419.7 9899.6 10031.6 10035.2 10196.8 10296.6 3256.2 3258.8 3275.8 3275.9 3370.3 62464.0 63095.7 63532.4 63784.0 64294.7 9620.2 9704.6 9735.8 9828.4 9929.7 18413.1 18420.1 18548.4 18557.2 18629.2 6301.9 6458.8 6469.0 6490.4 6550.0 3297.2 3439.7 3460.0 3470.3 3487.3 3024.3 3097.5 3107.4 3135.6 3162.4 491.8 492.5 494.2 494.7 507.1 524.9 532.2 538.5 547.5 549.9 7438.5 7700.8 8084.4 8115.1 8172.0 443.3 445.5 460.2 495.2 498.4 3465.6 3622.6 3762.8 3770.8 3936.3 46641.0 49650.0 56158.0 58757.0 60476.4 52210.7 52308.5 52826.2 53600.2 53606.7 46749.0 47094.3 47492.8 47807.3 48792.5 7416.3 7627.5 7734.9 7819.7 7829.3 33218.0 33243.3 33631.5 34352.0 34990.5 39511.6 41233.6 41894.0 42337.2 43741.3 8782.4 9052.8 9087.6 9239.8 9281.1 9647.0 9707.8 9734.9 9791.4 9865.8 9616.5 9821.0 10109.1 10115.5 10320.2 9090.5 9236.2 9482.9 9499.4 9629.8 8038.0 8243.8 8274.0 8422.6 8654.0 8114.9 8163.8 8424.5 8494.6 8568.3 7975.3 8025.1 8124.4 8294.4 8343.9 19:45:49 INFO - PROCESS | 11648 | -------- Summary: end -------- 19:45:49 INFO - PROCESS | 11648 | 19:45:50 INFO - TEST-INFO | 11648: exit 0 19:45:50 INFO - TEST-OK | dromaeo_css | took 1119609ms 19:45:50 INFO - TEST-START | kraken 19:45:50 INFO - operating with platform_type : linux_ 19:45:50 INFO - Initialising browser for kraken test... 19:45:50 INFO - TEST-INFO | started process 12023 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAg3OOn/profile http://localhost:34408/getInfo.html) 19:45:53 INFO - PROCESS | 12023 | Iterating over profile /tmp/tmpAg3OOn/profile 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/safebrowsing = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/gmp = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/user.js = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/key3.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/cache2 = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/cert8.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/prefs.js = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/extensions.ini = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/localstore.rdf = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/content-prefs.sqlite = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/addons.json = Wed Feb 22 2017 19:45:53 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/crashes = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/directoryLinks.json = Wed Feb 22 2017 19:45:53 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/extensions.json = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/frequencyCap.json = Wed Feb 22 2017 19:45:53 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/.parentlock = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 12023 | __metrics Screen width/height:1600/1200 19:45:54 INFO - PROCESS | 12023 | colorDepth:24 19:45:54 INFO - PROCESS | 12023 | Browser inner width/height: 1024/697 19:45:54 INFO - PROCESS | 12023 | __metrics 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/places.sqlite = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/permissions.sqlite = Wed Feb 22 2017 19:45:51 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/compatibility.ini = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/places.sqlite-shm = Wed Feb 22 2017 19:45:51 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/thumbnails = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/startupCache = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/extensions = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/places.sqlite-wal = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/blocklist.xml = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | Stat failure 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/minidumps = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/bookmarkbackups = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/pluginreg.dat = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:55 INFO - PROCESS | 12023 | No birth date. Using mtime. 19:45:55 INFO - PROCESS | 12023 | Using date: /tmp/tmpAg3OOn/profile/secmod.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 ERROR - PROCESS | 12023 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:45:56 INFO - TEST-INFO | 12023: exit 0 19:45:56 INFO - Browser initialized. 19:45:56 INFO - Running cycle 1/1 for kraken test... 19:45:56 INFO - TEST-INFO | started process 12152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAg3OOn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:08 INFO - PROCESS | 12152 | RSS: Main: 153821184 19:46:08 INFO - PROCESS | 12152 | 19:46:11 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34408/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:12 INFO - PROCESS | 12152 | RSS: Main: 160075776 19:46:12 INFO - PROCESS | 12152 | 19:46:15 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34408/tests/kraken/driver.html?testName=audio-dft) 19:46:16 INFO - PROCESS | 12152 | RSS: Main: 159563776 19:46:16 INFO - PROCESS | 12152 | 19:46:18 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34408/tests/kraken/driver.html?testName=audio-fft) 19:46:19 INFO - PROCESS | 12152 | RSS: Main: 156368896 19:46:19 INFO - PROCESS | 12152 | 19:46:21 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34408/tests/kraken/driver.html?testName=audio-oscillator) 19:46:22 INFO - PROCESS | 12152 | RSS: Main: 156762112 19:46:22 INFO - PROCESS | 12152 | 19:46:24 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34408/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:25 INFO - PROCESS | 12152 | RSS: Main: 157216768 19:46:25 INFO - PROCESS | 12152 | 19:46:28 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34408/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:28 INFO - PROCESS | 12152 | RSS: Main: 161624064 19:46:28 INFO - PROCESS | 12152 | 19:46:32 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34408/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:33 INFO - PROCESS | 12152 | RSS: Main: 158552064 19:46:33 INFO - PROCESS | 12152 | 19:46:36 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34408/tests/kraken/driver.html?testName=json-parse-financial) 19:46:36 INFO - PROCESS | 12152 | RSS: Main: 159551488 19:46:36 INFO - PROCESS | 12152 | 19:46:38 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34408/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:39 INFO - PROCESS | 12152 | RSS: Main: 159854592 19:46:39 INFO - PROCESS | 12152 | 19:46:41 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:41 INFO - PROCESS | 12152 | RSS: Main: 159735808 19:46:41 INFO - PROCESS | 12152 | 19:46:43 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:44 INFO - PROCESS | 12152 | RSS: Main: 159678464 19:46:44 INFO - PROCESS | 12152 | 19:46:47 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:46:48 INFO - PROCESS | 12152 | RSS: Main: 160317440 19:46:48 INFO - PROCESS | 12152 | 19:46:51 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:46:51 INFO - PROCESS | 12152 | RSS: Main: 163500032 19:46:51 INFO - PROCESS | 12152 | 19:46:53 INFO - PROCESS | 12152 | Cycle 1(1): loaded http://localhost:34408/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34408/tests/kraken/driver.html?testName=ai-astar) 19:46:54 INFO - PROCESS | 12152 | RSS: Main: 164229120 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | __start_tp_report 19:46:54 INFO - PROCESS | 12152 | _x_x_mozilla_page_load 19:46:54 INFO - PROCESS | 12152 | _x_x_mozilla_page_load_details 19:46:54 INFO - PROCESS | 12152 | |i|pagename|runs| 19:46:54 INFO - PROCESS | 12152 | |0;ai-astar;110;114;118;116;115;116;116;116;116;115 19:46:54 INFO - PROCESS | 12152 | |1;audio-beat-detection;157;127;128;189;130;129;130;130;153;140 19:46:54 INFO - PROCESS | 12152 | |2;audio-dft;166;170;167;170;168;166;166;164;164;167 19:46:54 INFO - PROCESS | 12152 | |3;audio-fft;76;77;77;103;78;78;85;77;101;77 19:46:54 INFO - PROCESS | 12152 | |4;audio-oscillator;85;85;105;85;86;85;104;84;84;112 19:46:54 INFO - PROCESS | 12152 | |5;imaging-gaussian-blur;114;114;114;115;114;114;115;114;114;113 19:46:54 INFO - PROCESS | 12152 | |6;imaging-darkroom;151;151;151;151;151;151;151;152;151;153 19:46:54 INFO - PROCESS | 12152 | |7;imaging-desaturate;99;97;96;97;97;97;96;97;101;96 19:46:54 INFO - PROCESS | 12152 | |8;json-parse-financial;65;66;66;64;66;67;65;67;66;66 19:46:54 INFO - PROCESS | 12152 | |9;json-stringify-tinderbox;66;66;66;66;66;66;65;66;66;66 19:46:54 INFO - PROCESS | 12152 | |10;stanford-crypto-aes;89;90;90;89;98;88;89;90;89;90 19:46:54 INFO - PROCESS | 12152 | |11;stanford-crypto-ccm;138;139;140;150;138;144;145;149;197;140 19:46:54 INFO - PROCESS | 12152 | |12;stanford-crypto-pbkdf2;188;184;184;187;184;182;186;181;186;191 19:46:54 INFO - PROCESS | 12152 | |13;stanford-crypto-sha256-iterative;74;70;70;80;70;75;71;70;75;70 19:46:54 INFO - PROCESS | 12152 | __end_tp_report 19:46:54 INFO - PROCESS | 12152 | __start_cc_report 19:46:54 INFO - PROCESS | 12152 | _x_x_mozilla_cycle_collect,893 19:46:54 INFO - PROCESS | 12152 | __end_cc_report 19:46:54 INFO - PROCESS | 12152 | __startTimestamp1487821614074__endTimestamp 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | ------- Summary: start ------- 19:46:54 INFO - PROCESS | 12152 | Number of tests: 14 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.10 (1.8%) stddev-sans-first:1.09 19:46:54 INFO - PROCESS | 12152 | Values: 110.0 114.0 118.0 116.0 115.0 116.0 116.0 116.0 116.0 115.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:135.00 stddev:19.93 (14.8%) stddev-sans-first:20.32 19:46:54 INFO - PROCESS | 12152 | Values: 157.0 127.0 128.0 189.0 130.0 129.0 130.0 130.0 153.0 140.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#2] audio-dft Cycles:10 Average:166.80 Median:167.00 stddev:2.10 (1.3%) stddev-sans-first:2.20 19:46:54 INFO - PROCESS | 12152 | Values: 166.0 170.0 167.0 170.0 168.0 166.0 166.0 164.0 164.0 167.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#3] audio-fft Cycles:10 Average:82.90 Median:77.00 stddev:10.39 (13.5%) stddev-sans-first:10.71 19:46:54 INFO - PROCESS | 12152 | Values: 76.0 77.0 77.0 103.0 78.0 78.0 85.0 77.0 101.0 77.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:85.00 stddev:10.91 (12.8%) stddev-sans-first:11.31 19:46:54 INFO - PROCESS | 12152 | Values: 85.0 85.0 105.0 85.0 86.0 85.0 104.0 84.0 84.0 112.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 19:46:54 INFO - PROCESS | 12152 | Values: 114.0 114.0 114.0 115.0 114.0 114.0 115.0 114.0 114.0 113.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 19:46:54 INFO - PROCESS | 12152 | Values: 151.0 151.0 151.0 151.0 151.0 151.0 151.0 152.0 151.0 153.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:97.00 stddev:1.57 (1.6%) stddev-sans-first:1.54 19:46:54 INFO - PROCESS | 12152 | Values: 99.0 97.0 96.0 97.0 97.0 97.0 96.0 97.0 101.0 96.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 19:46:54 INFO - PROCESS | 12152 | Values: 65.0 66.0 66.0 64.0 66.0 67.0 65.0 67.0 66.0 66.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 19:46:54 INFO - PROCESS | 12152 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 65.0 66.0 66.0 66.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:90.00 stddev:2.82 (3.1%) stddev-sans-first:2.96 19:46:54 INFO - PROCESS | 12152 | Values: 89.0 90.0 90.0 89.0 98.0 88.0 89.0 90.0 89.0 90.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:144.50 stddev:17.76 (12.3%) stddev-sans-first:18.47 19:46:54 INFO - PROCESS | 12152 | Values: 138.0 139.0 140.0 150.0 138.0 144.0 145.0 149.0 197.0 140.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:186.00 stddev:2.95 (1.6%) stddev-sans-first:2.96 19:46:54 INFO - PROCESS | 12152 | Values: 188.0 184.0 184.0 187.0 184.0 182.0 186.0 181.0 186.0 191.0 19:46:54 INFO - PROCESS | 12152 | 19:46:54 INFO - PROCESS | 12152 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.50 stddev:3.41 (4.7%) stddev-sans-first:3.57 19:46:54 INFO - PROCESS | 12152 | Values: 74.0 70.0 70.0 80.0 70.0 75.0 71.0 70.0 75.0 70.0 19:46:54 INFO - PROCESS | 12152 | -------- Summary: end -------- 19:46:54 INFO - PROCESS | 12152 | 19:46:55 INFO - TEST-INFO | 12152: exit 0 19:46:55 INFO - TEST-OK | kraken | took 64966ms 19:46:55 INFO - SUITE-END | took 1184s 19:46:55 INFO - Completed test suite (00:19:44) 19:46:55 INFO - javascript benchmark 19:46:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6133.0472842395175, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2515.484325713229, 2538.987305063474, 2541.4917016596687, 2553.6680231569894, 2556.8338058026216, 4356.695031347807, 4504.166729155108, 4504.256797628392, 4582.679212455126, 4622.884427889305, 4399.846005389812, 4514.119746649405, 4527.547245275471, 4565.223911934971, 4583.404157459534, 4147.999999999993, 4215.494140703105, 4218.388333691613, 4272.2950713132295, 4279.358096285551, 4240.194363071022, 4256.914861702764, 4288.228118938606, 4289.056407590325, 4330.39374487572, 4107.9178416431805, 4133.21468920904, 4164.7709375984305, 4296.785160741982, 4308.569143085698, 4140.399642051885, 4162.542120366757, 4172.937405938914, 4209.116085622023, 4215.241256573816, 4140.627343539066, 4145.97927010366, 4149.668026557868, 4185.393117997874, 4190.0, 4411.647068234533, 4420.0, 4430.379746835446, 4508.053308805155, 4512.210363186462, 4847.175980083394, 4867.853964381075, 4868.853934381974, 4873.707577545359, 4950.702957822542, 4862.878428039292, 4922.483139270396, 4930.161872481687, 4931.5258674546885, 4976.7013979161, 4403.9779801100785, 4622.052479241781, 4626.768661566942, 4627.5603817637275, 4663.743494107856, 4781.139394908881, 4918.040982008545, 4938.456769755324, 4955.826546070943, 4975.0547395994645, 4605.700629459075, 4644.419447569054, 4778.283257511401, 4786.114568804782, 4789.449213340441, 4895.290182923456, 4898.485659005824, 4899.216125419915, 4936.185529387611, 4947.5794557462295, 4599.9080018399445, 4834.226523756252, 4865.805367785249, 4867.075255701406, 4869.123557759566, 4618.376519169869, 4805.183118869801, 4841.2496063110275, 4852.611791056707, 4912.877178070506, 4140.60664236897, 4148.709590328708, 4156.854510092132, 4179.519355274121, 4188.371744238389, 3629.129009037812, 3644.3804553226014, 3646.7811931283677, 3658.048907284072, 3682.226732386175, 4079.3676980068135, 4114.897127571835, 4195.916081678411, 4208.305629571087, 4221.746695198237, 4840.6127509799135, 4864.78108485119, 4865.513448655106, 4903.5586797188425, 4917.237828136645, 4221.852235171816, 4225.281702110648, 4235.7035007549175, 4236.406903033578, 4274.700770946004, 4208.494980602347, 4217.894552636209, 4347.195768782785, 4382.342648602735, 4403.779811009436, 4582.656300777388, 4595.0, 4610.654200934876, 4636.489986101458, 4642.7446490442, 4895.510448955076, 4901.50984901507, 4915.361003069578, 4919.409670839522, 4923.822817885104, 4503.099380124054, 4553.931691024571, 4571.839985600488, 4596.862094137181, 4631.58315751584, 4783.210770223013, 4791.952080479151, 4793.448753393404, 4802.855914322576, 4805.783739731791, 4851.272309153655, 4854.126257273725, 4875.219964805614, 4878.682885612424, 4911.7298548580175, 4561.429821272341, 4579.771011449481, 4712.575868171881, 4734.384530011076, 4769.093872164278, 4740.905181896412, 4763.118823017753, 4775.068861572099, 4806.423229212517, 4810.975945120252, 4671.252599584051, 4684.297355396581, 4706.717596944194, 4709.599684026896, 4765.9046819064115, 4564.566366195206, 4643.512431194676, 4740.265258884879, 4748.881277968078, 4802.8799280018275, 4682.929756053593, 4700.55344742249, 4700.811967521261, 4739.691920025188, 4740.597049250852], "value": 4467.175215712064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.2987262005223, 533.3386600615236, 535.7508758427332, 549.8020712543483, 581.9883602327956, 37035.629643703556, 39529.80235098824, 39532.0, 39677.60322396775, 39694.81678507681, 40068.799656001676, 40077.599224007754, 40366.79816600913, 40374.7981260094, 40423.79788101063, 7692.769216923487, 7842.960785196066, 7855.214478552128, 7863.37093032556, 9269.907300927005, 9419.811603767921, 9542.379745316566, 9553.474558899257, 9556.856647150298, 9557.474338911357, 9455.479948602824, 9512.33413661044, 9585.520723963775, 9591.856122158173, 9620.759481012961, 9400.0, 9478.715638530855, 9484.762880927998, 9508.762280942963, 9578.760530986781, 9329.067093290616, 9392.718218453458, 9407.388519746195, 9415.576299066559, 9426.0, 31544.211394715086, 31754.523682144787, 32003.679963200302, 32143.678563214304, 32163.03510894677, 3282.770206085575, 3398.9373345803924, 3422.760406771527, 3426.623071462137, 3463.705585025276, 25092.874535627387, 25205.87397063021, 25248.873755631288, 25476.490470190678, 25807.0, 3412.1128506588466, 3516.4000379827116, 3519.4720791881423, 3535.1692352297446, 3608.332458495187, 227495.0, 227799.0, 228236.0, 229462.0, 229679.0, 41767.582324176976, 42946.0, 46288.30567541489, 46663.76668116706, 46715.29927051097, 49160.50839491631, 49849.0, 50052.74973625181, 53754.73122634362, 54075.0, 12133.575324863587, 12301.384930753606, 12377.814332785016, 12390.442430090667, 12442.0, 9370.95314523423, 9401.341906066646, 9406.200472959876, 9421.0, 9445.291603129794, 15467.066616672915, 15491.380344786308, 15553.066815991077, 15626.374945002073, 15744.68510629781, 6411.0, 6594.571352862049, 6595.472362211011, 6612.603243805387, 6669.533132680665, 730.6602429870136, 747.5552046532305, 751.8796992481286, 758.5145506875604, 766.2835249042053, 25929.22212333633, 26643.200703978913, 27076.59385109225, 27223.0, 27430.314242143708, 878.547548012774, 910.6084383715066, 913.1370854542394, 931.2084727981162, 947.4220725357526, 13553.932230338785, 13908.944745698458, 13952.211753433432, 14088.788668169986, 14182.0, 116049.0, 116335.41832290785, 116373.0, 116481.41759291149, 116507.0, 47582.28626570604, 47915.281270780964, 47916.28125578119, 48069.0, 48145.0, 1112.593903225317, 1152.982705259422, 1177.9705507362385, 1177.9764404711857, 1180.0795379603924, 25294.367640808392, 25318.873405632854, 26036.73963260419, 26360.736392635827, 26519.0, 32742.34515309728, 32900.67099329072, 33048.50427243545, 33054.0, 33095.50356744602, 212407.0, 212710.0, 213140.0, 214073.0, 214120.0, 22434.0, 23331.533369332858, 23351.532969340857, 24015.279541613778, 24587.0, 35510.82244588761, 35541.1114722134, 35805.64194358023, 36297.0, 36442.27115457729, 30034.699653004063, 30213.546796797626, 30733.692663073976, 31323.373532528767, 31928.36143277075, 30057.849710751307, 30394.0, 30545.694543054284, 30753.38493230078, 31586.368272633958, 29270.853645731637, 29900.252493686963, 30269.243268918453, 30405.695943040286, 30970.0, 26177.73822261753, 27296.317592060357, 27861.58207626928, 27913.860430697718, 28027.57958630663, 21457.892710536347, 21908.0, 21945.122195112017, 22122.55754884861, 22132.55734885261], "value": 15829.271406480382, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.1419420178505, 389.7895136626222, 402.4968789013732, 408.1775222925805, 428.188582635905, 12392.566260180896, 12532.561360352389, 16573.00561966283, 17970.28118875245, 17988.37040703574, 14121.92939035306, 17134.14329283537, 17488.65022699545, 17564.473065808015, 17657.20542575584, 5076.340075790152, 5245.344331958505, 5251.923355712089, 5275.340582427197, 5310.07073762092, 5106.340439357224, 5197.298364720752, 5203.323567936163, 5288.206768984644, 5305.0450918834595, 5237.633365664405, 5262.500062494076, 5268.604854635899, 5268.841934741945, 5281.366236051679, 5098.107831129538, 5148.613853960968, 5188.92216616751, 5201.193814958688, 5226.7909283628605, 4657.254839225741, 4664.86005419838, 4711.057788442326, 4714.292856071616, 4724.881877953045, 19440.611187776307, 20524.79475205244, 21796.673049904264, 21839.890800545894, 21975.23086691974, 2061.0184263564747, 2150.290404166621, 2176.5320456101854, 2192.6382146945734, 2218.5673793610254, 9427.622895084189, 9775.31572789905, 9872.308938374317, 12010.159288849783, 12584.559540416041, 1640.1717132847955, 1683.6194320656944, 1685.2837544043855, 1686.4434736536914, 1726.0506721303234, 68635.31364686339, 73559.26440735577, 76083.23916760873, 76530.0, 82230.0, 9098.590563424661, 9617.56720947559, 9643.517824108907, 10760.246782725135, 11199.944000279946, 12884.291363974884, 12916.870831291755, 12923.15999460032, 12952.417141228667, 13029.47882084706, 6623.138991931018, 6780.864382712318, 6820.931790682129, 6926.757563485254, 6990.370866622028, 5865.706714664249, 6048.516118710492, 6134.754609815557, 6152.569320147636, 6173.382661733881, 3955.841766329372, 3956.742811717229, 3981.5222173339384, 4012.879613611596, 4111.157212771374, 1924.6920492721097, 1944.2903340280695, 1947.6981067934494, 1972.556174860645, 1997.5106099005836, 762.5348537392186, 793.5159552672865, 797.5453991225062, 798.9081255655674, 809.3080416244144, 17411.47765567035, 17412.738808917875, 17733.7339939901, 18105.275788968294, 18604.81395186058, 782.7612578163714, 794.5471081483499, 795.1253621016835, 802.5345299726145, 818.7134502923928, 9470.810583788423, 9663.661771837951, 10007.199424046059, 10021.549030293509, 10157.441340726331, 342037.0, 347469.0, 350470.0, 497440.0, 501233.0, 87822.56088719516, 92745.5362723182, 94080.52959735157, 94902.0, 96257.51871240599, 945.2721404518483, 955.5174636808367, 986.5067466266867, 986.8766404199619, 987.3384832162468, 15687.215639218222, 16067.839321606634, 16803.579910501845, 16903.32386704518, 17101.23044462977, 39610.40584391179, 40017.399739003355, 40345.1930961385, 40438.32447577535, 41976.58023419727, 54743.0, 55169.44830551804, 55461.11772175909, 55623.72188139033, 57447.71276143593, 8025.23760242776, 8130.471519351223, 8384.287335576544, 8432.494050356998, 8691.217790398894, 9204.447733136034, 11779.941100294443, 12138.635840924786, 12480.0, 12494.750104998031, 5732.684702341245, 9009.864852027094, 10019.54912028975, 10529.631462898762, 10689.30519516229, 10550.366977981346, 10698.411587362458, 10745.355278683304, 10975.451227438436, 11002.614908478165, 10247.84628230592, 10271.332363396356, 10464.162866970471, 10514.57941682324, 10605.151587873106, 7765.572893490912, 7781.0, 7943.84112317762, 7958.323542498721, 8071.475354102199, 9495.430274183294, 9944.900550994687, 10608.787824243318, 10755.085817705583, 10841.783164336826], "value": 8299.058720623745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.9804852927207, 1315.1057281048888, 1316.433933408634, 1322.8346456692918, 1330.88022078013, 7028.824279393018, 7196.10048743907, 7382.7785166444955, 7415.369693576045, 7427.25727427257, 7226.891596626055, 7253.963730181355, 7278.272172782719, 7323.377512911409, 7328.083989501312, 2051.1077681208685, 2097.7063211150453, 2101.5271563898154, 2106.5681535285307, 2112.6725357569526, 2100.8550340064703, 2124.4582631428966, 2126.5853158634072, 2128.6913397557346, 2137.2092325839385, 2020.717099606049, 2063.0819285417997, 2071.637463443891, 2083.197968782022, 2083.9791602083938, 2008.4476768888599, 2033.3391647714477, 2045.447729113146, 2055.9588808223825, 2058.9485262868398, 1760.9207585658676, 1804.4857215693464, 2023.838092952575, 2038.8368930485526, 2131.872087674729, 7060.541064830821, 7278.563286202845, 7283.308085731847, 7323.7070517179245, 7394.445416593777, 4303.806328715215, 4470.351798989161, 4470.932936005963, 4480.103979204139, 4480.551944805494, 2822.1815673454676, 2839.0914907229694, 2882.322654176267, 2902.6516817981765, 2917.154025332651, 1077.9353238805618, 1121.556984990924, 1152.7406333574963, 1159.7796418680507, 1161.6340852631395, 9782.755431114208, 9804.852927206097, 9817.754556136082, 9917.454540000297, 9927.404355738607, 3747.5690295616355, 3830.751001184914, 3973.0, 4052.8986775330854, 4065.3495440729935, 7713.305802477804, 7723.575203363869, 7730.11103723079, 7735.187805280477, 7736.032995875516, 4166.104287578185, 4182.665386769051, 4187.748735075906, 4191.559886211965, 4202.579742025773, 3844.442555829445, 4012.8394864205106, 4053.5338436079637, 4065.654419374327, 4084.7140700150703, 3102.255458689898, 3136.717695407379, 3222.5971753530807, 3225.645179030305, 3227.0802821196316, 953.8235426445991, 954.9092836180552, 960.0735290444754, 972.4700038478904, 972.6401231544373, 736.6537727268176, 755.7883792538098, 767.9808004799925, 774.8217909880647, 782.6047845837816, 4639.698419602782, 4786.731516148154, 4808.06242782654, 5006.07387633289, 5074.213496907985, 854.8418542569644, 884.5311984648147, 889.1730690457936, 897.8832751742232, 921.8893732751978, 3270.7219886309454, 3314.7016768490953, 3316.7014968652456, 3354.530365748797, 3484.651534846495, 8491.19333663301, 8570.614322355385, 8765.474071555727, 8910.331725120643, 8969.596368163448, 9034.096590341045, 9040.276777857756, 9075.13786190311, 9088.500132492776, 9094.86357704635, 6607.504437167232, 6744.258131605519, 6758.560693554805, 6763.323667633197, 6789.219239787488, 4756.173687233112, 4760.880977975578, 4896.951030489649, 4947.6536642434685, 5143.2285157225215, 7785.377169826287, 7840.960795195988, 7864.095629002738, 7874.960625196837, 8071.71748988806, 7779.144294127528, 8024.438289319692, 8095.757127286191, 8096.311813495683, 8136.877946830684, 6304.338044505261, 6417.1657684500715, 6444.355564443518, 6491.448226900767, 6518.380753828568, 6900.654967251517, 6920.100386949664, 6944.895826562707, 6983.755568555076, 7028.718851246097, 6205.6586887721605, 6242.094896240111, 6247.718852651732, 6288.591241569284, 6430.164078669743, 6264.404881536247, 6278.309385967448, 6313.652749098844, 6340.714667839866, 6476.287608362982, 5369.630744160214, 5395.919061214006, 5443.8094666686475, 5445.673259604437, 5457.426970168234, 4418.160549495586, 4439.089986552814, 4454.220511410425, 4494.303382975644, 4507.323901414814, 4250.447441832542, 4333.826646934087, 4463.04044630406, 4476.552344765498, 4560.0], "value": 3870.9452484737258, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.34005389759454, 348.9877854275098, 364.46423757077093, 364.5406787447813, 366.4942379516267, 4922.261660750889, 4951.504849515051, 4953.232249001401, 4968.3292755478, 4973.403191617011, 4839.733814640203, 4968.627352948534, 4991.875203119924, 4999.8000079996755, 5018.899622007558, 3908.6482216600502, 4013.1973605278913, 4031.9395209071886, 4039.919201615973, 4042.110735638155, 4025.5773143819915, 4033.7579745215235, 4047.8583249586272, 4070.837166513336, 4078.062045729469, 4020.959790402088, 4038.7778672173017, 4050.230456213325, 4060.2082593894056, 4060.8629583716606, 3915.0, 4035.717499775017, 4052.9999999999854, 4053.8581149659767, 4067.959320406788, 3608.981955090234, 3624.4382120771324, 3636.472711456824, 3658.9451158232646, 3659.9817000914827, 4874.65877388583, 4916.680415772963, 4923.163062279421, 4939.827106051307, 4950.183219768734, 4579.335996280521, 4607.4471063472265, 4612.308153776927, 4614.30785382192, 4644.976775116136, 4978.77595508203, 5235.842924712265, 5246.842594722164, 5252.6323157379, 5305.124654432014, 4017.4375587417785, 4027.8388864445387, 4032.697547683935, 4033.4151548025666, 4037.253108174997, 5236.293100431431, 5374.247605335257, 5512.173174023888, 5532.336119665626, 5584.385717571064, 3994.0613955720382, 4088.8977775555254, 4096.078382363944, 4112.49893788765, 4130.215259100762, 4150.190712810973, 4173.686973477002, 4175.206710724952, 4175.436316097349, 4188.308929026676, 4425.7829096998585, 4607.470140933836, 4607.792649330788, 4623.237165867594, 4640.257558790578, 5196.922046169235, 5227.242049902743, 5245.659032162897, 5251.868703282448, 5300.549453296436, 1373.7046534995025, 1379.2138481065697, 1396.804447377368, 1397.8462369139386, 1398.3078376203641, 960.011188475871, 1005.797303904447, 1009.3792317724693, 1011.5700827148432, 1016.4905115902878, 4455.1312494064, 4471.396361491222, 4475.373447717322, 4481.103779244099, 4481.731096134243, 5155.871103222449, 5322.946770532322, 5330.866728331823, 5340.439253878287, 5343.973280133574, 4300.0, 4468.865934021985, 4491.967921652871, 4492.550744925481, 4520.118576877478, 2774.847383393933, 2787.4007088476073, 2796.6084748135277, 2810.552565428806, 2811.2550174203852, 4896.216605343128, 4905.460399356068, 4920.852374428773, 4921.3356196912955, 4921.434035086011, 5149.665271757323, 5321.281626980308, 5360.91958620621, 5402.6758394496455, 5486.506214440719, 4976.552110310089, 5018.924716129261, 5021.573166280834, 5035.219540971156, 5066.771995260148, 5350.892982140414, 5422.539084177889, 5440.836774896759, 5443.965646527147, 5452.055889819611, 5187.221916712523, 5315.3090098286975, 5322.920156197738, 5350.555350055541, 5386.811461598982, 4795.20879054952, 4902.3872015998, 4959.20652695567, 5024.145895197752, 5041.269015992587, 3792.5259342582176, 3825.8087095645665, 3832.48261484696, 3844.788536630512, 3856.9228615428096, 5436.592255580768, 5447.0, 5447.428020057995, 5454.727263636882, 5470.64440811346, 4940.344984430256, 5245.0, 5249.737513124405, 5306.708131052829, 5323.201519772065, 5237.554807841224, 5244.875825290764, 5248.790048398174, 5266.262723218676, 5282.6038047147385, 5192.299039629602, 5195.896082078261, 5196.454372290855, 5211.530962213419, 5224.440024388098, 4961.50384961501, 5241.31862857826, 5264.15773476242, 5273.472652734696, 5285.814996475105, 5120.74396280177, 5132.46109158533, 5136.1268584340005, 5137.563307118937, 5145.3568303962], "value": 4017.669133728452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.1799455693993, 662.5229834519145, 723.7901008707476, 727.7307396263383, 760.8668483015473, 11974.281543107409, 13021.414036368362, 13033.283169425702, 13116.081874268804, 13122.343882805857, 12897.355132243398, 12956.352182390867, 13044.73910521789, 13063.73872522549, 13069.869301306984, 780.9648565814524, 792.9167437419075, 799.1528979281951, 803.1968031968032, 807.4993504027511, 794.364508393283, 801.4550105927966, 802.1176705623797, 802.8394321135767, 805.4643661964786, 818.9222023907749, 820.6553247635977, 821.2321479416735, 823.798169448483, 826.8470332988386, 813.9267465928066, 819.6024927909959, 823.8187598728271, 825.2572684583893, 826.0500424511799, 606.7391021860598, 609.05596325695, 609.6768712582339, 610.6000569626901, 612.0849330251258, 13055.347232638425, 13208.339583020907, 13329.266890321029, 13334.066615336931, 13461.932690336584, 12668.0, 13101.47594096235, 13121.606351809462, 13369.064165508418, 13419.66450838727, 9899.554520046615, 10031.59873605055, 10035.247356448295, 10196.847047294297, 10296.588136474533, 3256.2022304535303, 3258.837058147083, 3275.819829909354, 3275.901722948315, 3370.3427831572853, 62464.0, 63095.684521577554, 63532.36467635357, 63784.0, 64294.67852660754, 9620.230381569458, 9704.563294651758, 9735.756606084762, 9828.361156524803, 9929.702108936743, 18413.079346032646, 18420.07899605041, 18548.350807721665, 18557.16492757829, 18629.161687724085, 6301.936980630135, 6458.773942911976, 6468.967655161789, 6490.350964903472, 6550.0, 3297.20866991925, 3439.7420193485586, 3460.0311912664492, 3470.323286959019, 3487.250241198104, 3024.259056531164, 3097.4889143291557, 3107.409592177479, 3135.623725152996, 3162.367526494664, 491.8229437402532, 492.5493173746054, 494.1771949703775, 494.70317809314844, 507.07711964225496, 524.8897731476357, 532.2335836395578, 538.5018857556744, 547.4826289156788, 549.85978575463, 7438.516496427715, 7700.807479813049, 8084.353251739847, 8115.107338192912, 8171.959140204261, 443.3040127000643, 445.46766613896085, 460.16480088639014, 495.15081634996153, 498.38449514849134, 3465.601455832561, 3622.565292164957, 3762.830672619684, 3770.7549009314307, 3936.252112098692, 46641.0, 49650.0, 56158.0, 58757.0, 60476.39523604708, 52210.73894630503, 52308.47691523112, 52826.207606885924, 53600.19599706007, 53606.73196633992, 46749.0, 47094.29358559624, 47492.76253618779, 47807.282890756665, 48792.51207487951, 7416.25837416254, 7627.5042122261875, 7734.8839767402405, 7819.726309579138, 7829.29536341732, 33218.0, 33243.33513329768, 33631.495527566614, 34352.0, 34990.47514287237, 39511.60488395079, 41233.58766412418, 41894.0, 42337.15325693407, 43741.25034998565, 8782.429142105742, 9052.773680658036, 9087.59105840252, 9239.815203695754, 9281.071892810665, 9646.951765241129, 9707.805843882941, 9734.902650973683, 9791.412515249107, 9865.802683946424, 9616.51917404113, 9821.0, 10109.140723038625, 10115.494225288854, 10320.225983051452, 9090.54547272647, 9236.214921731727, 9482.857757133786, 9499.43003419797, 9629.807403852024, 8037.959810201145, 8243.83512329762, 8273.958630206811, 8422.620982055945, 8653.956730216309, 8114.918850811652, 8163.79590510242, 8424.452410593292, 8494.575271236537, 8568.271696905833, 7975.322097621767, 8025.117237104031, 8124.350051995944, 8294.419390642597, 8343.916560834557], "value": 5830.382602140186, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 118.0, 116.0, 115.0, 116.0, 116.0, 116.0, 116.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 128.0, 189.0, 130.0, 129.0, 130.0, 130.0, 153.0, 140.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 167.0, 170.0, 168.0, 166.0, 166.0, 164.0, 164.0, 167.0], "value": 166.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 103.0, 78.0, 78.0, 85.0, 77.0, 101.0, 77.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 105.0, 85.0, 86.0, 85.0, 104.0, 84.0, 84.0, 112.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 151.0, 152.0, 151.0, 153.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 96.0, 97.0, 97.0, 97.0, 96.0, 97.0, 101.0, 96.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 64.0, 66.0, 67.0, 65.0, 67.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 89.0, 98.0, 88.0, 89.0, 90.0, 89.0, 90.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 140.0, 150.0, 138.0, 144.0, 145.0, 149.0, 197.0, 140.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 184.0, 187.0, 184.0, 182.0, 186.0, 181.0, 186.0, 191.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 70.0, 80.0, 70.0, 75.0, 71.0, 70.0, 75.0, 70.0], "value": 72.5, "unit": "score"}]}]} 19:46:55 INFO - Return code: 0 19:46:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:46:55 ERROR - # TBPL SUCCESS # 19:46:55 INFO - Running post-action listener: _resource_record_post_action 19:46:55 INFO - [mozharness: 2017-02-23 03:46:55.858015Z] Finished run-tests step (success) 19:46:55 INFO - Running post-run listener: _resource_record_post_run 19:46:55 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 360370176; Read time: 24; Write time: 211700 19:46:55 INFO - TinderboxPrint: CPU usage
13.5% 19:46:55 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 19:46:55 INFO - TinderboxPrint: I/O write bytes / time
360,370,176 / 211,700 19:46:55 INFO - TinderboxPrint: CPU idle
8,285.5 (86.5%) 19:46:55 INFO - TinderboxPrint: CPU user
1,233.9 (12.9%) 19:46:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:46:55 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105938944; Read time: 0; Write time: 86568 19:46:55 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 254431232; Read time: 24; Write time: 125132 19:46:56 INFO - Running post-run listener: _upload_blobber_files 19:46:56 INFO - Blob upload gear active. 19:46:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:46:56 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 19:46:56 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'] 19:46:56 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 19:46:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:05 INFO - (blobuploader) - INFO - Done attempting. 19:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:47:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:06 INFO - (blobuploader) - INFO - Done attempting. 19:47:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:47:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:07 INFO - (blobuploader) - INFO - Done attempting. 19:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:08 INFO - (blobuploader) - INFO - Done attempting. 19:47:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:08 INFO - Return code: 0 19:47:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78d9062c9a56b3b15f02c6fc8ed04539572f72c4edc8b0b29886f9a34d9c5b243646ecf5a78f681c91384d8ca8655b3061868298120a067cbfb475c922ae4ec9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8cbf683a15fc5a9a87d9ac8026cdedf7a3aee87e1f189eacb17510c99257fbc889fb9d5e8be1fad444804c2a4a5a529658fafb1d917a5cb15721d2e6ef8f986", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b32912dc3b8c2d4108e68e3fc74b8c4800a8fb12ba08fbae545fd5351fada2f06923d7c0fea82aed082c8b2bcab689a4a59d0c12067e3239508187120503aad2"} 19:47:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:08 INFO - Contents: 19:47:08 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78d9062c9a56b3b15f02c6fc8ed04539572f72c4edc8b0b29886f9a34d9c5b243646ecf5a78f681c91384d8ca8655b3061868298120a067cbfb475c922ae4ec9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8cbf683a15fc5a9a87d9ac8026cdedf7a3aee87e1f189eacb17510c99257fbc889fb9d5e8be1fad444804c2a4a5a529658fafb1d917a5cb15721d2e6ef8f986", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b32912dc3b8c2d4108e68e3fc74b8c4800a8fb12ba08fbae545fd5351fada2f06923d7c0fea82aed082c8b2bcab689a4a59d0c12067e3239508187120503aad2"} 19:47:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:08 INFO - Copying logs to upload dir... 19:47:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.439756 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-02-22 19:47:08.914559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.918017) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 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/78d9062c9a56b3b15f02c6fc8ed04539572f72c4edc8b0b29886f9a34d9c5b243646ecf5a78f681c91384d8ca8655b3061868298120a067cbfb475c922ae4ec9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8cbf683a15fc5a9a87d9ac8026cdedf7a3aee87e1f189eacb17510c99257fbc889fb9d5e8be1fad444804c2a4a5a529658fafb1d917a5cb15721d2e6ef8f986", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b32912dc3b8c2d4108e68e3fc74b8c4800a8fb12ba08fbae545fd5351fada2f06923d7c0fea82aed082c8b2bcab689a4a59d0c12067e3239508187120503aad2"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78d9062c9a56b3b15f02c6fc8ed04539572f72c4edc8b0b29886f9a34d9c5b243646ecf5a78f681c91384d8ca8655b3061868298120a067cbfb475c922ae4ec9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d8cbf683a15fc5a9a87d9ac8026cdedf7a3aee87e1f189eacb17510c99257fbc889fb9d5e8be1fad444804c2a4a5a529658fafb1d917a5cb15721d2e6ef8f986", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b32912dc3b8c2d4108e68e3fc74b8c4800a8fb12ba08fbae545fd5351fada2f06923d7c0fea82aed082c8b2bcab689a4a59d0c12067e3239508187120503aad2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.944841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.945365) ========= 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=8887e36bb8e381bfd44124ac000001a8-1487820349.52692-165835224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:09.032056) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:09.032921) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:09.033646) ========= ========= Total master_lag: 0.19 =========