builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1487823688.25 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.247228) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.247689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.247973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.290211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.290485) ========= 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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:28-- 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% 46.0M=0s 2017-02-22 20:21:28 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084850 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.389078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.389390) ========= 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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020444 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.438700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.439015) ========= 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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:29,008 truncating revision to first 12 chars 2017-02-22 20:21:29,008 Setting DEBUG logging. 2017-02-22 20:21:29,008 attempt 1/10 2017-02-22 20:21:29,008 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 20:21:29,328 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634260 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.085741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.086044) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.086409) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.086658) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.098771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-22 20:21:29.099049) ========= /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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:29 INFO - MultiFileLogger online at 20170222 20:21:29 in /builds/slave/test 20:21:29 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 20:21:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:29 INFO - {'append_to_log': False, 20:21:29 INFO - 'base_work_dir': '/builds/slave/test', 20:21:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:29 INFO - 'branch': 'Firefox-Non-PGO', 20:21:29 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:29 INFO - 'config_files': ('talos/linux_config.py',), 20:21:29 INFO - 'default_actions': ('clobber', 20:21:29 INFO - 'read-buildbot-config', 20:21:29 INFO - 'download-and-extract', 20:21:29 INFO - 'populate-webroot', 20:21:29 INFO - 'create-virtualenv', 20:21:29 INFO - 'install', 20:21:29 INFO - 'run-tests'), 20:21:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:29 INFO - 'download_minidump_stackwalk': True, 20:21:29 INFO - 'download_symbols': 'ondemand', 20:21:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:29 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:29 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:29 INFO - 'gecko_profile': False, 20:21:29 INFO - 'gecko_profile_interval': 0, 20:21:29 INFO - 'installer_path': 'installer.exe', 20:21:29 INFO - 'log_level': 'info', 20:21:29 INFO - 'log_name': 'talos', 20:21:29 INFO - 'log_to_console': True, 20:21:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:29 INFO - 'opt_config_files': (), 20:21:29 INFO - 'pip_index': False, 20:21:29 INFO - 'suite': 'dromaeojs-e10s', 20:21:29 INFO - 'system_bits': '32', 20:21:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:29 INFO - 'title': 'talos-linux64-ix-054', 20:21:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:29 INFO - 'use_talos_json': True, 20:21:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:29 INFO - 'work_dir': 'build'} 20:21:29 INFO - [mozharness: 2017-02-23 04:21:29.769116Z] Running clobber step. 20:21:29 INFO - Running pre-action listener: _resource_record_pre_action 20:21:29 INFO - Running main action method: clobber 20:21:29 INFO - rmtree: /builds/slave/test/build 20:21:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:30 INFO - Running post-action listener: _resource_record_post_action 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.683046Z] Finished clobber step (success) 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.683145Z] Running read-buildbot-config step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: read_buildbot_config 20:21:30 INFO - Using buildbot properties: 20:21:30 INFO - { 20:21:30 INFO - "project": "", 20:21:30 INFO - "product": "firefox", 20:21:30 INFO - "who": "kwierso@gmail.com", 20:21:30 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:30 INFO - "stage_platform": "linux64", 20:21:30 INFO - "basedir": "/builds/slave/test", 20:21:30 INFO - "buildnumber": 689, 20:21:30 INFO - "platform": "ubuntu64_hw", 20:21:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:21:30 INFO - "slavebuilddir": "test", 20:21:30 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:30 INFO - "branch": "mozilla-central", 20:21:30 INFO - "script_repo_revision": "production", 20:21:30 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:30 INFO - "slavename": "talos-linux64-ix-054", 20:21:30 INFO - "repo_path": "mozilla-central" 20:21:30 INFO - } 20:21:30 INFO - Finding installer, test and symbols from parent task. 20:21:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:31 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:32 INFO - Running post-action listener: _resource_record_post_action 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.152990Z] Finished read-buildbot-config step (success) 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.153205Z] Running download-and-extract step. 20:21:32 INFO - Running pre-action listener: _resource_record_pre_action 20:21:32 INFO - Running main action method: download_and_extract 20:21:32 INFO - mkdir: /builds/slave/test/build/tests 20:21:32 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')]} 20:21:32 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:32 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 20:21:32 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 20:21:32 INFO - Downloaded 896 bytes. 20:21:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:32 INFO - Using the following test package requirements: 20:21:32 INFO - {u'common': [u'target.common.tests.zip'], 20:21:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:32 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:32 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:32 INFO - u'target.web-platform.tests.zip'], 20:21:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:32 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 20:21:32 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 20:21:32 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:35 INFO - Expected file size: 57165889 20:21:35 INFO - Obtained file size: 57165889 20:21:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:21:45 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 20:21:45 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 20:21:45 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:46 INFO - Expected file size: 57165889 20:21:46 INFO - Obtained file size: 57165889 20:21:56 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 20:21:56 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 20:21:56 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:21:57 INFO - Expected file size: 14404319 20:21:57 INFO - Obtained file size: 14404319 20:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:57 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:21:57 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 20:21:59 INFO - Downloaded 61547669 bytes. 20:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:59 INFO - mkdir: /builds/slave/test/properties 20:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 20:21:59 INFO - Contents: 20:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:59 INFO - Running post-action listener: _resource_record_post_action 20:21:59 INFO - Running post-action listener: set_extra_try_arguments 20:21:59 INFO - [mozharness: 2017-02-23 04:21:59.307697Z] Finished download-and-extract step (success) 20:21:59 INFO - [mozharness: 2017-02-23 04:21:59.307793Z] Running populate-webroot step. 20:21:59 INFO - Running pre-action listener: _resource_record_pre_action 20:21:59 INFO - Running main action method: populate_webroot 20:21:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:21:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:21:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:21:59 INFO - '--tppagecycles', 20:21:59 INFO - '7'], 20:21:59 INFO - 'tests': ['tsvgm']}}, 20:21:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:21:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:21:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:21:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:21:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:21:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:21:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['damp', 'tps']}, 20:21:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'tests': ['damp', 'tps']}, 20:21:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['dromaeo_dom']}, 20:21:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:21:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['basic_compositor_video']}, 20:21:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:21:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'other-e10s': {'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['a11yr', 20:21:59 INFO - 'ts_paint', 20:21:59 INFO - 'tpaint', 20:21:59 INFO - 'sessionrestore', 20:21:59 INFO - 'sessionrestore_no_auto_restore', 20:21:59 INFO - 'tabpaint']}, 20:21:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['tsvgx', 20:21:59 INFO - 'tsvgr_opacity', 20:21:59 INFO - 'tart', 20:21:59 INFO - 'tscrollx', 20:21:59 INFO - 'cart', 20:21:59 INFO - 'tsvg_static']}, 20:21:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:21:59 INFO - 'tsvgr_opacity', 20:21:59 INFO - 'tart', 20:21:59 INFO - 'tscrollx', 20:21:59 INFO - 'cart', 20:21:59 INFO - 'tsvg_static']}, 20:21:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'talos_options': ['--disable-e10s'], 20:21:59 INFO - 'tests': ['tp5o']}, 20:21:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:21:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'talos_options': ['--disable-e10s', 20:21:59 INFO - '--xperf_path', 20:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:21:59 INFO - 'tests': ['tp5n']}, 20:21:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:21:59 INFO - 'talos_options': ['--xperf_path', 20:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:21:59 INFO - 'tests': ['tp5n']}}, 20:21:59 INFO - 'talos.zip': {'path': '', 20:21:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:21:59 INFO - Running post-action listener: _resource_record_post_action 20:21:59 INFO - [mozharness: 2017-02-23 04:21:59.320816Z] Finished populate-webroot step (success) 20:21:59 INFO - [mozharness: 2017-02-23 04:21:59.320905Z] Running create-virtualenv step. 20:21:59 INFO - Running pre-action listener: _resource_record_pre_action 20:21:59 INFO - Running main action method: create_virtualenv 20:21:59 INFO - Creating virtualenv /builds/slave/test/build/venv 20:21:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:21:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:21:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:21:59 INFO - Using real prefix '/usr' 20:21:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:21:59 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:01 INFO - Installing pip.................done. 20:22:01 INFO - Return code: 0 20:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:02 INFO - Reading from file tmpfile_stdout 20:22:02 INFO - Output received: 20:22:02 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) 20:22:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:22:02 INFO - Using env: {'DISPLAY': ':0', 20:22:02 INFO - 'HOME': '/home/cltbld', 20:22:02 INFO - 'LANG': 'en_US.UTF-8', 20:22:02 INFO - 'LANGUAGE': 'en_US:en', 20:22:02 INFO - 'LOGNAME': 'cltbld', 20:22:02 INFO - 'MAIL': '/var/mail/cltbld', 20:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:02 INFO - 'MOZ_NO_REMOTE': '1', 20:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:02 INFO - 'NO_EM_RESTART': '1', 20:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:02 INFO - 'PWD': '/builds/slave/test', 20:22:02 INFO - 'SHELL': '/bin/bash', 20:22:02 INFO - 'SHLVL': '1', 20:22:02 INFO - 'TERM': 'linux', 20:22:02 INFO - 'TMOUT': '86400', 20:22:02 INFO - 'USER': 'cltbld', 20:22:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:02 INFO - '_': '/tools/buildbot/bin/python'} 20:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:22:02 INFO - Cleaning up... 20:22:02 INFO - Return code: 0 20:22:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:22:02 INFO - Using env: {'DISPLAY': ':0', 20:22:02 INFO - 'HOME': '/home/cltbld', 20:22:02 INFO - 'LANG': 'en_US.UTF-8', 20:22:02 INFO - 'LANGUAGE': 'en_US:en', 20:22:02 INFO - 'LOGNAME': 'cltbld', 20:22:02 INFO - 'MAIL': '/var/mail/cltbld', 20:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:02 INFO - 'MOZ_NO_REMOTE': '1', 20:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:02 INFO - 'NO_EM_RESTART': '1', 20:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:02 INFO - 'PWD': '/builds/slave/test', 20:22:02 INFO - 'SHELL': '/bin/bash', 20:22:02 INFO - 'SHLVL': '1', 20:22:02 INFO - 'TERM': 'linux', 20:22:02 INFO - 'TMOUT': '86400', 20:22:02 INFO - 'USER': 'cltbld', 20:22:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:02 INFO - '_': '/tools/buildbot/bin/python'} 20:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:02 INFO - Downloading/unpacking psutil>=3.1.1 20:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:05 INFO - Installing collected packages: psutil 20:22:05 INFO - Running setup.py install for psutil 20:22:05 INFO - building 'psutil._psutil_linux' extension 20:22:05 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 20:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:22:05 INFO - building 'psutil._psutil_posix' extension 20:22:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:05 INFO - Successfully installed psutil 20:22:05 INFO - Cleaning up... 20:22:05 INFO - Return code: 0 20:22:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:22:05 INFO - Using env: {'DISPLAY': ':0', 20:22:05 INFO - 'HOME': '/home/cltbld', 20:22:05 INFO - 'LANG': 'en_US.UTF-8', 20:22:05 INFO - 'LANGUAGE': 'en_US:en', 20:22:05 INFO - 'LOGNAME': 'cltbld', 20:22:05 INFO - 'MAIL': '/var/mail/cltbld', 20:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:05 INFO - 'MOZ_NO_REMOTE': '1', 20:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:05 INFO - 'NO_EM_RESTART': '1', 20:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:05 INFO - 'PWD': '/builds/slave/test', 20:22:05 INFO - 'SHELL': '/bin/bash', 20:22:05 INFO - 'SHLVL': '1', 20:22:05 INFO - 'TERM': 'linux', 20:22:05 INFO - 'TMOUT': '86400', 20:22:05 INFO - 'USER': 'cltbld', 20:22:05 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:05 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:08 INFO - Installing collected packages: mozsystemmonitor 20:22:08 INFO - Running setup.py install for mozsystemmonitor 20:22:08 INFO - Successfully installed mozsystemmonitor 20:22:08 INFO - Cleaning up... 20:22:08 INFO - Return code: 0 20:22:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:08 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')]} 20:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:08 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')]} 20:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:08 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:08 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 20:22:08 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 20:22:08 INFO - Using env: {'DISPLAY': ':0', 20:22:08 INFO - 'HOME': '/home/cltbld', 20:22:08 INFO - 'LANG': 'en_US.UTF-8', 20:22:08 INFO - 'LANGUAGE': 'en_US:en', 20:22:08 INFO - 'LOGNAME': 'cltbld', 20:22:08 INFO - 'MAIL': '/var/mail/cltbld', 20:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:08 INFO - 'MOZ_NO_REMOTE': '1', 20:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:08 INFO - 'NO_EM_RESTART': '1', 20:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:08 INFO - 'PWD': '/builds/slave/test', 20:22:08 INFO - 'SHELL': '/bin/bash', 20:22:08 INFO - 'SHLVL': '1', 20:22:08 INFO - 'TERM': 'linux', 20:22:08 INFO - 'TMOUT': '86400', 20:22:08 INFO - 'USER': 'cltbld', 20:22:08 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:08 INFO - '_': '/tools/buildbot/bin/python'} 20:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:08 INFO - Downloading/unpacking jsonschema==2.5.1 20:22:08 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 20:22:08 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 20:22:08 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 20:22:08 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 20:22:08 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 20:22:08 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 20:22:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:10 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:10 INFO - warning: no files found matching '*.txt' 20:22:10 INFO - no previously-included directories found matching 'build' 20:22:10 INFO - no previously-included directories found matching 'dist' 20:22:10 INFO - no previously-included directories found matching '.git*' 20:22:10 INFO - Installing collected packages: jsonschema, functools32 20:22:10 INFO - Running setup.py install for functools32 20:22:10 INFO - warning: no files found matching '*.txt' 20:22:10 INFO - no previously-included directories found matching 'build' 20:22:10 INFO - no previously-included directories found matching 'dist' 20:22:10 INFO - no previously-included directories found matching '.git*' 20:22:10 INFO - Successfully installed jsonschema functools32 20:22:10 INFO - Cleaning up... 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:11 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')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:11 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 20:22:11 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 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:11 INFO - Cleaning up... 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:11 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')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:11 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 20:22:11 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 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:11 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:11 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 20:22:11 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 20:22:11 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 20:22:11 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 20:22:11 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 20:22:11 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 20:22:13 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - Downloading docopt-0.6.1.tar.gz 20:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:14 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:14 INFO - Running setup.py install for blobuploader 20:22:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:14 INFO - Running setup.py install for requests 20:22:14 INFO - Running setup.py install for docopt 20:22:14 INFO - Successfully installed blobuploader requests docopt 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:15 INFO - Running setup.py (path:/tmp/pip-soNLMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:15 INFO - Running setup.py (path:/tmp/pip-z9fhAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:15 INFO - Running setup.py (path:/tmp/pip-JrYtMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:15 INFO - Running setup.py (path:/tmp/pip-utPVrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:15 INFO - Running setup.py (path:/tmp/pip-SWdH8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:15 INFO - Running setup.py (path:/tmp/pip-iy8d1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:15 INFO - Running setup.py (path:/tmp/pip-b9bt3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:15 INFO - Running setup.py (path:/tmp/pip-00cSdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:15 INFO - Running setup.py (path:/tmp/pip-WOGA5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:15 INFO - Running setup.py (path:/tmp/pip-zsVCk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:15 INFO - Running setup.py (path:/tmp/pip-TNt1dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:15 INFO - Running setup.py (path:/tmp/pip-PwClcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:15 INFO - Running setup.py (path:/tmp/pip-xi1DmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:16 INFO - Running setup.py (path:/tmp/pip-wttJMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:16 INFO - Running setup.py (path:/tmp/pip-EaVMwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:16 INFO - Running setup.py (path:/tmp/pip-Ifg9AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:16 INFO - Running setup.py (path:/tmp/pip-0bFqDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:16 INFO - Running setup.py install for manifestparser 20:22:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:16 INFO - Running setup.py install for mozcrash 20:22:16 INFO - Running setup.py install for mozdebug 20:22:16 INFO - Running setup.py install for mozdevice 20:22:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:16 INFO - Running setup.py install for mozfile 20:22:16 INFO - Running setup.py install for mozhttpd 20:22:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:16 INFO - Running setup.py install for mozinfo 20:22:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for mozInstall 20:22:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for mozleak 20:22:17 INFO - Running setup.py install for mozlog 20:22:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for moznetwork 20:22:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for mozprocess 20:22:17 INFO - Running setup.py install for mozprofile 20:22:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for mozrunner 20:22:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:17 INFO - Running setup.py install for mozscreenshot 20:22:17 INFO - Running setup.py install for moztest 20:22:18 INFO - Running setup.py install for mozversion 20:22:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:18 INFO - Cleaning up... 20:22:18 INFO - Return code: 0 20:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:18 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')]} 20:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 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')]} 20:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:18 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 20:22:18 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 20:22:18 INFO - Using env: {'DISPLAY': ':0', 20:22:18 INFO - 'HOME': '/home/cltbld', 20:22:18 INFO - 'LANG': 'en_US.UTF-8', 20:22:18 INFO - 'LANGUAGE': 'en_US:en', 20:22:18 INFO - 'LOGNAME': 'cltbld', 20:22:18 INFO - 'MAIL': '/var/mail/cltbld', 20:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:18 INFO - 'MOZ_NO_REMOTE': '1', 20:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:18 INFO - 'NO_EM_RESTART': '1', 20:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:18 INFO - 'PWD': '/builds/slave/test', 20:22:18 INFO - 'SHELL': '/bin/bash', 20:22:18 INFO - 'SHLVL': '1', 20:22:18 INFO - 'TERM': 'linux', 20:22:18 INFO - 'TMOUT': '86400', 20:22:18 INFO - 'USER': 'cltbld', 20:22:18 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:18 INFO - '_': '/tools/buildbot/bin/python'} 20:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Running setup.py (path:/tmp/pip-z96pUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Running setup.py (path:/tmp/pip-sJXXqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Running setup.py (path:/tmp/pip-ZD5i0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Running setup.py (path:/tmp/pip-TyNziK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Running setup.py (path:/tmp/pip-_k94In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Running setup.py (path:/tmp/pip-Nb5TFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:18 INFO - Running setup.py (path:/tmp/pip-P8eaUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:18 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)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:18 INFO - Running setup.py (path:/tmp/pip-vw_5ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:18 INFO - Running setup.py (path:/tmp/pip-PoEyOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Running setup.py (path:/tmp/pip-1ThpbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Running setup.py (path:/tmp/pip-Jp1_uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Running setup.py (path:/tmp/pip-1GYtIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Running setup.py (path:/tmp/pip-5V19iY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Running setup.py (path:/tmp/pip-zWABNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Running setup.py (path:/tmp/pip-tSM7_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Running setup.py (path:/tmp/pip-uWxPE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:19 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)) 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Running setup.py (path:/tmp/pip-rxA5jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:19 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)) 20:22:19 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)) 20:22:19 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)) 20:22:19 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)) 20:22:19 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)) 20:22:19 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)) 20:22:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:19 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 20:22:19 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 20:22:19 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 20:22:19 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 20:22:19 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 20:22:19 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 20:22:21 INFO - Downloading blessings-1.6.tar.gz 20:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:21 INFO - Installing collected packages: blessings 20:22:21 INFO - Running setup.py install for blessings 20:22:21 INFO - Successfully installed blessings 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:22 INFO - Reading from file tmpfile_stdout 20:22:22 INFO - Current package versions: 20:22:22 INFO - argparse == 1.2.1 20:22:22 INFO - blessings == 1.6 20:22:22 INFO - blobuploader == 1.2.4 20:22:22 INFO - docopt == 0.6.1 20:22:22 INFO - functools32 == 3.2.3-2 20:22:22 INFO - jsonschema == 2.5.1 20:22:22 INFO - manifestparser == 1.1 20:22:22 INFO - mozInstall == 1.12 20:22:22 INFO - mozcrash == 1.0 20:22:22 INFO - mozdebug == 0.1 20:22:22 INFO - mozdevice == 0.49 20:22:22 INFO - mozfile == 1.2 20:22:22 INFO - mozhttpd == 0.7 20:22:22 INFO - mozinfo == 0.9 20:22:22 INFO - mozleak == 0.1 20:22:22 INFO - mozlog == 3.4 20:22:22 INFO - moznetwork == 0.27 20:22:22 INFO - mozprocess == 0.25 20:22:22 INFO - mozprofile == 0.28 20:22:22 INFO - mozrunner == 6.13 20:22:22 INFO - mozscreenshot == 0.1 20:22:22 INFO - mozsystemmonitor == 0.3 20:22:22 INFO - moztest == 0.8 20:22:22 INFO - mozversion == 1.4 20:22:22 INFO - psutil == 3.1.1 20:22:22 INFO - requests == 1.2.3 20:22:22 INFO - wsgiref == 0.1.2 20:22:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:22 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')]} 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:22 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')]} 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:22 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:22 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 20:22:22 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 20:22:22 INFO - Using env: {'DISPLAY': ':0', 20:22:22 INFO - 'HOME': '/home/cltbld', 20:22:22 INFO - 'LANG': 'en_US.UTF-8', 20:22:22 INFO - 'LANGUAGE': 'en_US:en', 20:22:22 INFO - 'LOGNAME': 'cltbld', 20:22:22 INFO - 'MAIL': '/var/mail/cltbld', 20:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:22 INFO - 'MOZ_NO_REMOTE': '1', 20:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:22 INFO - 'NO_EM_RESTART': '1', 20:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:22 INFO - 'PWD': '/builds/slave/test', 20:22:22 INFO - 'SHELL': '/bin/bash', 20:22:22 INFO - 'SHLVL': '1', 20:22:22 INFO - 'TERM': 'linux', 20:22:22 INFO - 'TMOUT': '86400', 20:22:22 INFO - 'USER': 'cltbld', 20:22:22 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:22 INFO - '_': '/tools/buildbot/bin/python'} 20:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 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)) 20:22:22 INFO - Cleaning up... 20:22:22 INFO - Return code: 0 20:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:22 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')]} 20:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:22 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')]} 20:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:22 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 0xf391f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, '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': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', '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 20:22:22 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 20:22:22 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 20:22:22 INFO - Using env: {'DISPLAY': ':0', 20:22:22 INFO - 'HOME': '/home/cltbld', 20:22:22 INFO - 'LANG': 'en_US.UTF-8', 20:22:22 INFO - 'LANGUAGE': 'en_US:en', 20:22:22 INFO - 'LOGNAME': 'cltbld', 20:22:22 INFO - 'MAIL': '/var/mail/cltbld', 20:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:22 INFO - 'MOZ_NO_REMOTE': '1', 20:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:22 INFO - 'NO_EM_RESTART': '1', 20:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:22 INFO - 'PWD': '/builds/slave/test', 20:22:22 INFO - 'SHELL': '/bin/bash', 20:22:22 INFO - 'SHLVL': '1', 20:22:22 INFO - 'TERM': 'linux', 20:22:22 INFO - 'TMOUT': '86400', 20:22:22 INFO - 'USER': 'cltbld', 20:22:22 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:22 INFO - '_': '/tools/buildbot/bin/python'} 20:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:22 INFO - Cleaning up... 20:22:22 INFO - Return code: 0 20:22:22 INFO - Running post-action listener: _resource_record_post_action 20:22:22 INFO - Running post-action listener: _start_resource_monitoring 20:22:22 INFO - Starting resource monitoring. 20:22:22 INFO - [mozharness: 2017-02-23 04:22:22.821601Z] Finished create-virtualenv step (success) 20:22:22 INFO - [mozharness: 2017-02-23 04:22:22.822072Z] Running install step. 20:22:22 INFO - Running pre-action listener: _resource_record_pre_action 20:22:22 INFO - Running main action method: install 20:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:23 INFO - Reading from file tmpfile_stdout 20:22:23 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:23 INFO - Reading from file tmpfile_stdout 20:22:23 INFO - Output received: 20:22:23 INFO - Usage: mozinstall [options] installer 20:22:23 INFO - Options: 20:22:23 INFO - -h, --help show this help message and exit 20:22:23 INFO - -d DEST, --destination=DEST 20:22:23 INFO - Directory to install application into. [default: 20:22:23 INFO - "/builds/slave/test"] 20:22:23 INFO - --app=APP Application being installed. [default: firefox] 20:22:23 INFO - mkdir: /builds/slave/test/build/application 20:22:23 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'] 20:22:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:22:38 INFO - Reading from file tmpfile_stdout 20:22:38 INFO - Output received: 20:22:38 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:38 INFO - Running post-action listener: _resource_record_post_action 20:22:38 INFO - [mozharness: 2017-02-23 04:22:38.551728Z] Finished install step (success) 20:22:38 INFO - [mozharness: 2017-02-23 04:22:38.551879Z] Running run-tests step. 20:22:38 INFO - Running pre-action listener: _resource_record_pre_action 20:22:38 INFO - Running main action method: run_tests 20:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:38 INFO - Python 2.7.3 20:22:38 INFO - Return code: 0 20:22:38 INFO - grabbing minidump binary from tooltool 20:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d4290>, '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 0x11d4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d5040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:22:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:22:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:22:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:38 INFO - Return code: 0 20:22:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:38 INFO - ENV: RUST_BACKTRACE is now 1 20:22:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-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-054', '--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 20:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-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-054 --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 20:22:38 INFO - Using env: {'DISPLAY': ':0', 20:22:38 INFO - 'HOME': '/home/cltbld', 20:22:38 INFO - 'LANG': 'en_US.UTF-8', 20:22:38 INFO - 'LANGUAGE': 'en_US:en', 20:22:38 INFO - 'LOGNAME': 'cltbld', 20:22:38 INFO - 'MAIL': '/var/mail/cltbld', 20:22:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:38 INFO - 'MOZ_NO_REMOTE': '1', 20:22:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:38 INFO - 'NO_EM_RESTART': '1', 20:22:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:38 INFO - 'PWD': '/builds/slave/test', 20:22:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:38 INFO - 'RUST_BACKTRACE': '1', 20:22:38 INFO - 'SHELL': '/bin/bash', 20:22:38 INFO - 'SHLVL': '1', 20:22:38 INFO - 'TERM': 'linux', 20:22:38 INFO - 'TMOUT': '86400', 20:22:38 INFO - 'USER': 'cltbld', 20:22:38 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209', 20:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:38 INFO - '_': '/tools/buildbot/bin/python'} 20:22:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-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-054', '--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 20:22:38 INFO - mozversion application_buildid: 20170221181700 20:22:38 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:38 INFO - mozversion application_display_name: Nightly 20:22:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:38 INFO - mozversion application_name: Firefox 20:22:38 INFO - mozversion application_remotingname: firefox 20:22:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:38 INFO - mozversion application_vendor: Mozilla 20:22:38 INFO - mozversion application_version: 54.0a1 20:22:38 INFO - mozversion platform_buildid: 20170221181700 20:22:38 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:38 INFO - mozversion platform_version: 54.0a1 20:22:38 INFO - using testdate: 1487823758 20:22:38 INFO - actual date: 1487823758 20:22:38 INFO - starting webserver on 'localhost:56772' 20:22:38 INFO - SUITE-START | Running 2 tests 20:22:38 INFO - TEST-START | dromaeo_css 20:22:38 INFO - operating with platform_type : linux_ 20:22:38 INFO - Initialising browser for dromaeo_css test... 20:22:38 INFO - TEST-INFO | started process 20655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwGKe10/profile http://localhost:56772/getInfo.html) 20:22:41 INFO - PROCESS | 20655 | Iterating over profile /tmp/tmpwGKe10/profile 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/places.sqlite-wal = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/content-prefs.sqlite = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/mimeTypes.rdf = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/compatibility.ini = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/extensions = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/directoryLinks.json = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:42 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:42 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/bookmarkbackups = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/cache2 = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/localstore.rdf = Wed Feb 22 2017 20:21:57 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/places.sqlite = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/extensions.json = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/addons.json = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/safebrowsing = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/permissions.sqlite = Wed Feb 22 2017 20:22:40 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/key3.db = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/startupCache = Wed Feb 22 2017 20:22:38 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/pluginreg.dat = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | Stat failure 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/cert8.db = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/places.sqlite-shm = Wed Feb 22 2017 20:22:40 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | __metrics Screen width/height:1600/1200 20:22:43 INFO - PROCESS | 20655 | colorDepth:24 20:22:43 INFO - PROCESS | 20655 | Browser inner width/height: 1024/697 20:22:43 INFO - PROCESS | 20655 | __metrics 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/prefs.js = Wed Feb 22 2017 20:21:57 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/minidumps = Wed Feb 22 2017 20:22:38 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/.parentlock = Wed Feb 22 2017 20:22:38 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/extensions.ini = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/thumbnails = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/gmp = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/secmod.db = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/frequencyCap.json = Wed Feb 22 2017 20:22:41 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/crashes = Wed Feb 22 2017 20:22:38 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/user.js = Wed Feb 22 2017 20:22:38 GMT-0800 (PST) 20:22:43 INFO - PROCESS | 20655 | No birth date. Using mtime. 20:22:43 INFO - PROCESS | 20655 | Using date: /tmp/tmpwGKe10/profile/blocklist.xml = Wed Feb 22 2017 20:22:39 GMT-0800 (PST) 20:22:44 ERROR - PROCESS | 20655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:22:45 INFO - TEST-INFO | 20655: exit 0 20:22:45 INFO - Browser initialized. 20:22:45 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:45 INFO - TEST-INFO | started process 20781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwGKe10/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:57 INFO - PROCESS | 20781 | RSS: Main: 153800704 20:22:57 INFO - PROCESS | 20781 | 20:26:01 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56772/tests/dromaeo/cssquery-ext.html) 20:26:02 INFO - PROCESS | 20781 | RSS: Main: 162979840 20:26:02 INFO - PROCESS | 20781 | 20:27:46 INFO - PROCESS | 20781 | ************************* 20:27:46 INFO - PROCESS | 20781 | A coding exception was thrown and uncaught in a Task. 20:27:46 INFO - PROCESS | 20781 | 20:27:46 ERROR - PROCESS | 20781 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:27:46 INFO - PROCESS | 20781 | Full stack: 20:27:46 INFO - PROCESS | 20781 | ************************* 20:29:05 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-ext.html (next: http://localhost:56772/tests/dromaeo/cssquery-jquery.html) 20:29:05 INFO - PROCESS | 20781 | RSS: Main: 158371840 20:29:05 INFO - PROCESS | 20781 | 20:32:07 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56772/tests/dromaeo/cssquery-mootools.html) 20:32:08 INFO - PROCESS | 20781 | RSS: Main: 167661568 20:32:08 INFO - PROCESS | 20781 | 20:35:10 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56772/tests/dromaeo/cssquery-prototype.html) 20:35:11 INFO - PROCESS | 20781 | RSS: Main: 158420992 20:35:11 INFO - PROCESS | 20781 | 20:38:14 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56772/tests/dromaeo/cssquery-yui.html) 20:38:14 INFO - PROCESS | 20781 | RSS: Main: 166305792 20:38:14 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | Cycle 1(1): loaded http://localhost:56772/tests/dromaeo/cssquery-yui.html (next: http://localhost:56772/tests/dromaeo/cssquery-dojo.html) 20:41:17 INFO - PROCESS | 20781 | RSS: Main: 158760960 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | __start_tp_report 20:41:17 INFO - PROCESS | 20781 | _x_x_mozilla_page_load 20:41:17 INFO - PROCESS | 20781 | _x_x_mozilla_page_load_details 20:41:17 INFO - PROCESS | 20781 | |i|pagename|runs| 20:41:17 INFO - PROCESS | 20781 | |0;dojo.html;2474.4308808973933;2549.898004079837;2553.757393047662;2557.4501482181327;2572.2283315005484;4395.318725597537;4489.034857505636;4523.954760452399;4600.17196904557;4623.7919293631785;4397.032652816375;4513.909721805562;4555.362249285103;4590.173768721629;4597.333386658933;4155.605217504348;4189.937150942738;4195.8741237762915;4220.620144187023;4290.099079193373;4264.616184543391;4268.935965960513;4279.87160385189;4302.784860756965;4333.523312435629;4076.6331030207125;4100.753954762724;4135.8966025849295;4284.400183974246;4314.26657468228;4181.707280490336;4205.411242426063;4223.113146239293;4254.765987870666;4262.46719160105;4174.70777045607;4188.4136220929095;4207.095474473003;4223.028703398205;4237.555056719031;4339.544347843463;4376.124775044972;4512.12013657337;4554.908901821978;4580.358749775034;4821.276808478721;4840.878978025542;4855.198892182786;4871.415430148405;4936.703797772145;4894;4899.681520701143;4902.754862256908;4904.337914381548;4945.085159245551;4479.283314669638;4587.42657167854;4589.747563883985;4594.19601569724;4630.930536041962;4894.608431325485;4928.581070608967;4942.233953737176;4953.6532442729385;5029.622778291643;4686.789094490756;4723.031778485402;4729;4732.02993386355;4732.479427262998;4833.95166048335;4837.032593481318;4847.103285892051;4878.341423907798;4900.754962251873;4682.321063445849;4815.711057336571;4821.662483626183;4828;4828.855134345975;4706.482286948433;4745.83389581363;4784.545468180518;4796.712197268175;4850.175470170009;4164.042270277853;4192.769397683096;4193.1404062167185;4196.076863090116;4228.260054490452;3621.2576421833987;3628.0748409155494;3634.9818250908575;3659.012066742018;3662.7252956028406;4144.8134833932545;4177.498700156001;4206.453161089039;4217.915641687149;4225.112726327444;4871.658983871166;4873.512648735098;4877;4930.087933732271;4930.605551555867;4230.111676547898;4239.872803815891;4244.0663054128045;4248.001719595893;4287.013986783058;4292.656587472995;4323.135372925428;4398.75806830621;4404.405405270311;4431.711938723973;4628.907421851477;4648.65135114861;4655.23188673865;4676.368690226844;4696.882577935579;4876.073546026386;4958.826441074545;4959.20652695567;4968.453470118357;5001.999600079926;4501.324801279834;4532.1842068427995;4538.72767633943;4648.233041548242;4659.161350956725;4790.928136077892;4841.15279826022;4843.418789745253;4851.636127290397;4874.268859671078;4894.828680996148;4904.043711476228;4904.288878112725;4917.877053073702;4930.975345123395;4583.220852455157;4607.838725644587;4681.438227412732;4687.296905464208;4714.528547145258;4717.646176536842;4730.503297153887;4737.715737055788;4779.880502987453;4780.067886762048;4660.930086048644;4684.391029166187;4685.812567497262;4720.551547602972;4752.405949256343;4661.067786442793;4706.270528068155;4720.881977950441;4748.738819364969;4804.807807687654;4654.138984287918;4657.254839225708;4673.088747694167;4704.670673052853;4720.409948756405 20:41:17 INFO - PROCESS | 20781 | |1;ext.html;520.126188004153;528.4398537550197;534.409477527332;550.6668465578325;560.2968274815106;37948.810255948716;39597.4060389094;39936;39941.400878986766;39942.60057399425;39762.80118599403;40224.39663405044;40305.19389612213;40357.39463908036;40708.389374159335;7565.5460672359695;7815.413843961697;7817.335526480228;7820.100688420819;7856.939313192732;9322.161005509503;9565.713028609152;9635.470049147294;9725.221982241439;9795.706128816111;9441.716748497556;9550.80898382032;9561.139497445229;9575.856362154538;9581.616735330614;9531.428114313128;9536.094071063239;9571.856422153673;9581.041895810364;9583.952080239555;9422.246220302359;9450.102240287231;9495.90504094964;9510.524473776353;9510.904890951071;33326.833365833256;33733.662663373296;33756.49365259523;33756.662433375845;33778.324433511436;3267.954314417278;3331.583552055993;3349.4138525757894;3397.4394224952853;3398.371301309241;25188.244352669448;25257.368565786004;25438.236852894443;25634;25835.483290334276;3399.2351720862857;3408.6080100788477;3475.2354482013916;3497.9825100874586;3502.1419752160627;233314;234548;234613;235492;236282;39986.400203996964;41139.1772164555;47327;47668;47704.7614761924;45103;45719;45933.35399566062;46148.538514614425;46176.3073553897;12300.01599872008;12319.63041108768;12379.381030948416;12387.690307742198;12439.564615238423;9326.626934922662;9414.19979301767;9431.104045115702;9463.526823658789;9478.289128315404;15750.291236894367;15812.446564370191;15838.841611583966;15905.761413578806;15906.76139857903;6422.197225346832;6585.341465853472;6639.468842492589;6654.101696271038;6656.733730650816;744.7505085796319;756.1191212237792;760.3650951455486;780.5862892666896;781.3007358414301;26316.342091447867;27464.487718565648;27602.309942251202;27752.583711244348;27798.583021254697;886.7738726624669;895.9552022398854;898.0435835834814;901.0808974845621;902.7968707040994;14038.929805350908;15885.920570397075;16490.422835200712;16545.91727041357;17230;324125;327148;329113;329453;330005;51904;53030;53161;53237.2014419784;53277.46722532802;1178.6935396796894;1186.4898093819568;1223.1560223445972;1223.8470191226097;1231.3166192763103;27213.455730884874;27976.160715178576;28637.427251455272;28875.7112428873;29062.709372906;43529.12941741211;44250.33624495571;44310.335344970495;44370.99999999871;44503.77748111239;292034;294012;294633;294900;295250;31398.215044624067;31827;32158.517622235217;32429.35141297208;37294;36809.44785828161;36949.26101477902;36950;37966.30505424208;38237.808810955765;31572.210694732814;31817.36365272728;31953.680463196;31954.52068219025;33436.164095897795;31407.528887066255;31624.209394765316;32001.6799832008;32454.35091298208;32713;31675.366492670477;31754.841225793723;31799;31852.68147318497;32281.35437291288;25761.742382575936;27441.31396715098;28946.27634309159;29066.854665726536;29192.708072919;22029.228976985727;22113.226037088625;22433.551328973655;22505.549889001803;22509.43726406853 20:41:17 INFO - PROCESS | 20781 | |2;jquery.html;363.6200170821491;379.92211596622695;395.8337498250734;402.53105132520614;418.8240938805701;16062.357505699772;17688.46934591961;18096.819031809693;18305.908470457663;18343.724844127333;16417.67164656706;16513.752293715603;16797.16014199288;16809.15954202288;18222.81777182228;5058.7976480940715;5265.499777521149;5268.947310526884;5274.709890955995;5277.683338999653;5123.718195499246;5221.086309895753;5345.4387289334645;5383.865403364927;5469.699166545858;5385.461453854603;5420.891582168354;5422.83731488054;5429.049916264657;5437.32033495813;5344.91982620259;5394.083005888989;5449.591280653947;5452.536534394581;5468.453154684539;5312.654677445954;5344.572434205294;5345.919811202835;5348.224507446398;5356.0894647909945;19418.22327106914;20066.799332006784;20180.49548761278;20662.5867482651;21249.893750531148;2076.262926661029;2150.6343921533376;2150.7849215078368;2154.396768904709;2196.967045494319;8962.910370896272;9303.11620396068;9475.147236748726;9805.950970245103;11176;1591.1725902530734;1593.1715507935924;1594.1869646480263;1611.8388161183905;1617.3126421271031;75138;77108.22891771067;78216.60891695562;79511;80930;10395.32430392022;10706.304090234109;10750.838737418788;10882.510287037103;10935.586884492228;15044.849551504563;15119;15129.848701513065;15174.24128793556;15243.542693719206;6654.968479885625;6922.619255940871;7035.6482175891;7111.395531379787;7113.0753002109395;6228.28374736902;6276.121343011982;6439.581427207217;6460.063290822804;6470.22357317125;4496.303073023687;4570.200214962369;4585.426821647294;4708.246680531099;4764.976175119103;1938.2925232290113;1946.250693482991;1973.8914359710066;1997.340877510418;2021.5047313408309;374.31313539654496;381.38596859056895;386.5091334005797;399.5804405374403;776.3711393771064;10869.402182880018;10912.454377281105;11042.723931901766;11043.619547556555;11656.533738650529;395.1346551053184;401.0495126550095;404.6095517825313;405.2401746724891;415.28778145480646;5887.76448942046;5905.498032667271;5918.852028699317;5963.701814909237;6047.0929360596265;493824;506136;511607;512072.0774772865;512424;81105.59447202727;83649.58175209085;84695;85333;86562.56718716492;925.2181906289175;957.6360982826482;958.0515289862985;960.0399600399601;961.0485619237045;16185.4335098271;16208.67582648364;16500.17499125015;16785.66428671444;16900.577485562466;38999.41500877546;39465.01337466471;39671.2065758689;40181.1963760729;40781;47939.28091078706;48466.5153348462;50540;54326.185107222635;58339.708301458224;7876.383295416498;7948.801279968047;8101.675932962851;8132.837343252983;8271.33829293643;10763.623273185402;11828.70428239301;11956.402179891145;12107.33409662477;12469.9448635085;5569.104065885797;10206.6427675034;10349.068583827491;10456.947715261374;10570.312929659547;6372.872542549216;10120.443375614117;10354.637587684394;10472.738181545523;10657;9825.705228843146;10067.395956242648;10474.004969527883;10852.077573406348;11067.778644427228;7918.604069796603;8001.919980800118;8114.959425203072;8139.511629302261;8408.159184081544;9839.360441571274;9968.750781230237;10135.442550659785;10328.328658637165;10843.1867609928 20:41:17 INFO - PROCESS | 20781 | |3;mootools.html;1334.3461703765158;1353.8849197818186;1367.5487089260546;1369.678125640475;1379.4620098161715;7385.7414990475345;7467.477276590627;7658.119316278629;7837.3338266247365;7846.215378462158;7706.344960678349;7765.184655611164;7810.414218933589;7822.921770782291;7860.842783144334;2074.419162634461;2108.5888251790907;2117.6717608770587;2120.957580848382;2125.404886631738;2089.9477513062143;2130.1266480742897;2132.3176583493287;2133.4026472587702;2136.6154092263387;2068.5759419319;2082.8437867159946;2109.4409981354875;2117.375374264596;2119.5972765174643;2098.58028394321;2107.8102970732707;2123.352377524854;2124.745030596323;2129.0525716056363;1760.6918789211966;1827.607064481143;2010.5476267839765;2013.2651582172548;2117.6188286108495;7768.805779855492;7997.760067197935;8003.559804210766;8010.439269251155;8010.439269251155;4516.864494065183;4645.349651048856;4650.348951146842;4652.325412815157;4652.930206046912;2904.2448963269703;2916.0814343481734;2950.27718209038;2995.026616349673;3025.3797971415806;1101.4162493878257;1135.7955567997758;1743.4508129939156;1805.3049575913237;1908.1794828223858;9989.700308990668;10271.486425678686;10318.43248621333;10466.581336746522;10596.152307815433;4036.6367026967714;4129.525104612948;4136.172765446922;4156.709030367845;4175.791210439465;8127.715529956423;8161.143079976636;8164.877526837102;8193.344532437392;8194.139615340304;4604.217283061887;4623.052274283764;4627.95372046282;4628.791704373311;4641.559051890065;4047.7976101195413;4267.8079486423185;4294.914101717948;4313.568643135662;4326.264535029052;3194.6645602211433;3258.3971965186515;3300.35643049603;3328.151321413024;3340.8329583521213;973.7030205787303;975.317277905469;1001.9699409017741;1004.1514919892679;1007.9848802267971;812.8049268175595;815.4454970620035;816.5386556595505;823.2138307915926;829.8298848735994;4989.301497790312;5167.32062079818;5209.973950130225;5280.683159010472;5356.116240820221;529.5948599321522;536.3135186960697;540.1627477410077;568.2527476368562;574.873527823878;3254.218987443044;3423.127102588824;3437.6390478999847;3446.121239084068;3554.59122200945;8215.712450064339;8568.657253709782;8794.164554367326;8797.868031979526;9058.599471140153;9387.014363491735;9395.765105872271;9399.24806015517;9408.106229908148;9424.717258482257;6819.24988251292;7024.929750702529;7113.288671132846;7346.302101300368;7375.704971801068;4974.035256073903;5049.491175067479;5118.334616499831;5237.8428647140645;5278;8478.957605211934;8572.614232359434;8654.48073115615;8712.303015758598;8712.78218044554;8358.498490090615;8685.739427816923;8691.174338437839;8725.607347669496;8778.648854045767;6372.904406434;6469.159009328757;6521.64130972801;6581.14445122131;6591.670416479061;7038.155421349471;7051.753188638373;7087.82280442993;7106.431485481253;7108.253633368422;6429.935700643121;6540.476761859135;6563.835904102436;6737.157855268091;6793.966030170015;6769.255381908085;6820.488463365168;6843.520953533404;6891.965540172267;6920.51556391068;5962.25471816023;6075.787347442995;6169.444749972345;6177.135201071764;6187.566870319214;4528.864134075983;4570.657200710027;4588.105319462807;4675.9064818702755;4704.5765881070865;4469.195544801967;4487.461504619467;4560.452745670541;4651.162790697707;4702.694324868872 20:41:17 INFO - PROCESS | 20781 | |4;prototype.html;344.91721986723184;351.09767896505974;357.5941306616989;365.84634453529515;373.0952440332194;4807.447143578481;4831.758412079391;5049.242613607956;5059.873503162423;5060;4842.2010368289275;4937.543424689716;4987.700737955725;5013.724245166514;5028.019536190435;3984.5417776955587;4038.9394159087637;4053.507093137714;4055.128147448298;4058.5129784425912;3829.3107240696663;3999.9000774399783;4039.151778126582;4049.3925911113274;4053.756774593534;3972.82122304497;3986.4219688145204;3986.760794352348;3993.381025940984;4008.2183974125055;3987.262356464049;3997.8800635980965;3998.980005099985;3999.2201520703475;4010.3583426651594;3611.620779818134;3624.401973674367;3673.2837096766143;3673.5591728992426;3693.242885208526;4633.513481084505;4869.5130486951375;4896.363472748555;4909;4934.051049835295;4396.978015109936;4408.493023302329;4461.286194208943;4469.486009108961;4472.127935052666;5209.7134657593815;5213.765380557884;5226.398964119099;5251.343582052244;5262.158054711266;3485.873617093571;3683.7291134558536;3730.8734021607693;3733.253349330145;3761.9247615047548;5335.413104558495;5435.239066530688;5443.455654434564;5469.261649677323;5512.3385193777;4034.2334956358786;4061.5126184858004;4073.796310184538;4077.6941729370415;4078;4186.811593478301;4192.664586833046;4194.846417235285;4200.6219440249915;4225.154969006149;4404.7357158570585;4634.58288754007;4655.11552804966;4669.245145364183;4672.369230153954;5229.555487783505;5287.233351164136;5290.338707661543;5302.893942121136;5320.946790532045;1398.985735341869;1418.4184484361363;1429.371076726237;1430;1432.5630682641895;943.1747221181466;987.9357841740264;996.4270544436978;1011.2870426349406;1020.2297265564451;4466.3523789050405;4483.417155769729;4511.819527218874;4513.593776560126;4519.615832654195;5199.714015729096;5224.582033437316;5228.379202447253;5228.607854410934;5248.658837175648;4291.592298731615;4464;4509.188346097669;4513.593776560126;4548.977255113703;2748.40909204522;2749.230215539652;2779.680336761258;2789.8326100434037;2800.4399120176045;4841.927371089437;4857.489963553847;4902.4362198346935;4951.741502308012;4953.281774142729;5183.792648294026;5350.08420410877;5396.568274538027;5408.864778380572;5527.894421115712;5045.9747701261995;5057.974710126426;5062.215356619729;5074.579117847009;5081.695098294114;5316.122839731401;5398.919016214839;5425.457454254543;5443.788164982521;5445.972770136124;5170.457102004386;5291.550218231493;5298.231756395378;5310.601704872072;5318.946810531846;4868.610511159028;4993.925091123709;5021.472745361831;5035.546800787946;5082.644214905069;3711.5917249102035;3735.2716220336774;3738.383166777451;3739.6073412291316;3747.526632422588;5443.455654434525;5450.890982180414;5464.562834973113;5467.234587157881;5474.178873169057;4992.327570263857;5239.711815850165;5247.291615631841;5284.577233821208;5335.946640533545;5166.199239117943;5240.4759524047295;5245.977034478393;5247.9737601311745;5249.238860365302;5128.153854613947;5133.435322114639;5156;5157.484251574812;5158.690478571298;4990.875228119181;5249.160134378481;5265.973670131625;5274;5288.947110528846;5166.689998600096;5176.818811341585;5176.948230517647;5190.48095190493;5225.503577160163 20:41:17 INFO - PROCESS | 20781 | |5;yui.html;644.1947565543071;656.3600489522713;712.5831388637647;734.8530293941211;763.3816608547075;11964.521419143235;13069.607911762638;13109.410076546554;13188.670283242924;13296.26870522122;12165.209261398002;12634.873651263508;12744.490220391173;12750.617481475547;12763.106582539225;796.4186144114785;812.6789917982424;814.2305521282391;815.0463957170125;815.5432957543765;781.4529829119639;797.1789057270992;804.6057431858405;805.7824387415394;807.0315621254518;834.8956380452444;844.4426678392263;846.5005646668465;848.1687945813106;849.6176720475811;841.1882533355341;842.0064324097563;846.9110743371949;849.477571293653;852.40757673417;618.2519151826295;620.4756980351596;621;621.4624349937271;621.8196722950339;12436.951288940392;12642.620721378373;12678.302693351861;12683.365831708377;13849.376778045013;12730.936345318307;12914.41885115172;12928.90104341132;13158.605241842664;13333.266670333127;10264.640737574224;10399.688009359656;10426.739331516697;10465.058144766932;10637.46812659364;3091.2117410060514;3109.5024796032753;3252.7560432967384;3252.9349413011846;3256.902292931216;60473.697631512;61381;61792.382076179114;61845;63236.367636323965;9521.714348569554;9716.61133554664;9836.655717049869;9860.112589866947;9967.55146018431;18572.349967751063;18587.34944276944;18855.151518181716;18904.338348157748;19147;5785.30576330843;5896.233489646319;5899.174115623816;5919.112133180058;6165.290991535941;3374.611919629274;3422.264213194175;3460.463628137643;3461.1866211440292;3510.7717998330027;3064.678208788045;3133.5143052826847;3144.8584813683437;3161.873525059018;3201.791883527563;500.0748615062106;504.13540777566647;504.629097613254;518.8988147311238;520.7474374928157;613.923259592551;627.2159800249688;632.3328888023087;632.7026297640102;633.1357696744003;8349.874751878726;8452.915470845213;8586.828263434696;8950.14973577509;9159.587818548182;560.9775608975677;576.420697199312;582.775631381921;587.7766448749447;588.3057991569902;4064.349704047338;4181.540030596632;4224.302990006676;4227.619514243733;4323.956760432418;55526.444735552934;64027;64252;65548;65892.67053664797;40284.798576006935;42008.57991420108;43271.78364108222;44056.77971610185;44306.113877722906;45868.08263834705;46383;46667.533324667;46680.83297917579;49610;8198.836023279382;9106.134917182857;9246.214071803972;9349.485778281987;9402.576884040098;33244.833775830964;34039;34159.487607685405;34445.48331774975;35170.64829351674;37998.43002354912;39621.40567891541;40257.1948561033;40407.39388909227;42425.78787106045;10139.2395570334;10167.84748228792;10191.184705223712;10195.694129176136;10449.16406687448;12291.44688489038;12344.691382715144;12594.937025314815;12613.68465788326;12849.486020559072;11907.46416411281;11955.760884782081;12182.939085304517;12220.816687749868;12291.385430728249;11794.882051179378;11952.820707689218;12205.20666156697;12246.693832654255;12645.557405491132;10494.947525262325;10743.497748934073;11104.944475277573;11105.722356941142;11107.833382499108;9443.291753118681;9724.02759724022;9779.804403912025;9834.459104749307;9959.651412200828;7952.296169569449;8764.34267429958;8932.240759535513;8956.731298061068;9060.184583387527 20:41:17 INFO - PROCESS | 20781 | __end_tp_report 20:41:17 INFO - PROCESS | 20781 | __start_cc_report 20:41:17 INFO - PROCESS | 20781 | _x_x_mozilla_cycle_collect,382 20:41:17 INFO - PROCESS | 20781 | __end_cc_report 20:41:17 INFO - PROCESS | 20781 | __startTimestamp1487824877678__endTimestamp 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | ------- Summary: start ------- 20:41:17 INFO - PROCESS | 20781 | Number of tests: 6 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#0] dojo.html Cycles:165 Average:4504.27 Median:4648.65 stddev:463.33 (10.0%) stddev-sans-first:436.53 20:41:17 INFO - PROCESS | 20781 | Values: 2474.4 2549.9 2553.8 2557.5 2572.2 4395.3 4489.0 4524.0 4600.2 4623.8 4397.0 4513.9 4555.4 4590.2 4597.3 4155.6 4189.9 4195.9 4220.6 4290.1 4264.6 4268.9 4279.9 4302.8 4333.5 4076.6 4100.8 4135.9 4284.4 4314.3 4181.7 4205.4 4223.1 4254.8 4262.5 4174.7 4188.4 4207.1 4223.0 4237.6 4339.5 4376.1 4512.1 4554.9 4580.4 4821.3 4840.9 4855.2 4871.4 4936.7 4894.0 4899.7 4902.8 4904.3 4945.1 4479.3 4587.4 4589.7 4594.2 4630.9 4894.6 4928.6 4942.2 4953.7 5029.6 4686.8 4723.0 4729.0 4732.0 4732.5 4834.0 4837.0 4847.1 4878.3 4900.8 4682.3 4815.7 4821.7 4828.0 4828.9 4706.5 4745.8 4784.5 4796.7 4850.2 4164.0 4192.8 4193.1 4196.1 4228.3 3621.3 3628.1 3635.0 3659.0 3662.7 4144.8 4177.5 4206.5 4217.9 4225.1 4871.7 4873.5 4877.0 4930.1 4930.6 4230.1 4239.9 4244.1 4248.0 4287.0 4292.7 4323.1 4398.8 4404.4 4431.7 4628.9 4648.7 4655.2 4676.4 4696.9 4876.1 4958.8 4959.2 4968.5 5002.0 4501.3 4532.2 4538.7 4648.2 4659.2 4790.9 4841.2 4843.4 4851.6 4874.3 4894.8 4904.0 4904.3 4917.9 4931.0 4583.2 4607.8 4681.4 4687.3 4714.5 4717.6 4730.5 4737.7 4779.9 4780.1 4660.9 4684.4 4685.8 4720.6 4752.4 4661.1 4706.3 4720.9 4748.7 4804.8 4654.1 4657.3 4673.1 4704.7 4720.4 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#1] ext.html Cycles:180 Average:43683.61 Median:3441.92 stddev:75496.89 (2193.5%) stddev-sans-first:75639.12 20:41:17 INFO - PROCESS | 20781 | Values: 520.1 528.4 534.4 550.7 560.3 37948.8 39597.4 39936.0 39941.4 39942.6 39762.8 40224.4 40305.2 40357.4 40708.4 7565.5 7815.4 7817.3 7820.1 7856.9 9322.2 9565.7 9635.5 9725.2 9795.7 9441.7 9550.8 9561.1 9575.9 9581.6 9531.4 9536.1 9571.9 9581.0 9584.0 9422.2 9450.1 9495.9 9510.5 9510.9 33326.8 33733.7 33756.5 33756.7 33778.3 3268.0 3331.6 3349.4 3397.4 3398.4 25188.2 25257.4 25438.2 25634.0 25835.5 3399.2 3408.6 3475.2 3498.0 3502.1 233314.0 234548.0 234613.0 235492.0 236282.0 39986.4 41139.2 47327.0 47668.0 47704.8 45103.0 45719.0 45933.4 46148.5 46176.3 12300.0 12319.6 12379.4 12387.7 12439.6 9326.6 9414.2 9431.1 9463.5 9478.3 15750.3 15812.4 15838.8 15905.8 15906.8 6422.2 6585.3 6639.5 6654.1 6656.7 744.8 756.1 760.4 780.6 781.3 26316.3 27464.5 27602.3 27752.6 27798.6 886.8 896.0 898.0 901.1 902.8 14038.9 15885.9 16490.4 16545.9 17230.0 324125.0 327148.0 329113.0 329453.0 330005.0 51904.0 53030.0 53161.0 53237.2 53277.5 1178.7 1186.5 1223.2 1223.8 1231.3 27213.5 27976.2 28637.4 28875.7 29062.7 43529.1 44250.3 44310.3 44371.0 44503.8 292034.0 294012.0 294633.0 294900.0 295250.0 31398.2 31827.0 32158.5 32429.4 37294.0 36809.4 36949.3 36950.0 37966.3 38237.8 31572.2 31817.4 31953.7 31954.5 33436.2 31407.5 31624.2 32001.7 32454.4 32713.0 31675.4 31754.8 31799.0 31852.7 32281.4 25761.7 27441.3 28946.3 29066.9 29192.7 22029.2 22113.2 22433.6 22505.5 22509.4 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#2] jquery.html Cycles:180 Average:27941.55 Median:4533.25 stddev:83490.86 (1841.7%) stddev-sans-first:83699.39 20:41:17 INFO - PROCESS | 20781 | Values: 363.6 379.9 395.8 402.5 418.8 16062.4 17688.5 18096.8 18305.9 18343.7 16417.7 16513.8 16797.2 16809.2 18222.8 5058.8 5265.5 5268.9 5274.7 5277.7 5123.7 5221.1 5345.4 5383.9 5469.7 5385.5 5420.9 5422.8 5429.0 5437.3 5344.9 5394.1 5449.6 5452.5 5468.5 5312.7 5344.6 5345.9 5348.2 5356.1 19418.2 20066.8 20180.5 20662.6 21249.9 2076.3 2150.6 2150.8 2154.4 2197.0 8962.9 9303.1 9475.1 9806.0 11176.0 1591.2 1593.2 1594.2 1611.8 1617.3 75138.0 77108.2 78216.6 79511.0 80930.0 10395.3 10706.3 10750.8 10882.5 10935.6 15044.8 15119.0 15129.8 15174.2 15243.5 6655.0 6922.6 7035.6 7111.4 7113.1 6228.3 6276.1 6439.6 6460.1 6470.2 4496.3 4570.2 4585.4 4708.2 4765.0 1938.3 1946.3 1973.9 1997.3 2021.5 374.3 381.4 386.5 399.6 776.4 10869.4 10912.5 11042.7 11043.6 11656.5 395.1 401.0 404.6 405.2 415.3 5887.8 5905.5 5918.9 5963.7 6047.1 493824.0 506136.0 511607.0 512072.1 512424.0 81105.6 83649.6 84695.0 85333.0 86562.6 925.2 957.6 958.1 960.0 961.0 16185.4 16208.7 16500.2 16785.7 16900.6 38999.4 39465.0 39671.2 40181.2 40781.0 47939.3 48466.5 50540.0 54326.2 58339.7 7876.4 7948.8 8101.7 8132.8 8271.3 10763.6 11828.7 11956.4 12107.3 12469.9 5569.1 10206.6 10349.1 10456.9 10570.3 6372.9 10120.4 10354.6 10472.7 10657.0 9825.7 10067.4 10474.0 10852.1 11067.8 7918.6 8001.9 8115.0 8139.5 8408.2 9839.4 9968.8 10135.4 10328.3 10843.2 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#3] mootools.html Cycles:180 Average:5000.53 Median:5142.83 stddev:2762.08 (53.7%) stddev-sans-first:2756.09 20:41:17 INFO - PROCESS | 20781 | Values: 1334.3 1353.9 1367.5 1369.7 1379.5 7385.7 7467.5 7658.1 7837.3 7846.2 7706.3 7765.2 7810.4 7822.9 7860.8 2074.4 2108.6 2117.7 2121.0 2125.4 2089.9 2130.1 2132.3 2133.4 2136.6 2068.6 2082.8 2109.4 2117.4 2119.6 2098.6 2107.8 2123.4 2124.7 2129.1 1760.7 1827.6 2010.5 2013.3 2117.6 7768.8 7997.8 8003.6 8010.4 8010.4 4516.9 4645.3 4650.3 4652.3 4652.9 2904.2 2916.1 2950.3 2995.0 3025.4 1101.4 1135.8 1743.5 1805.3 1908.2 9989.7 10271.5 10318.4 10466.6 10596.2 4036.6 4129.5 4136.2 4156.7 4175.8 8127.7 8161.1 8164.9 8193.3 8194.1 4604.2 4623.1 4628.0 4628.8 4641.6 4047.8 4267.8 4294.9 4313.6 4326.3 3194.7 3258.4 3300.4 3328.2 3340.8 973.7 975.3 1002.0 1004.2 1008.0 812.8 815.4 816.5 823.2 829.8 4989.3 5167.3 5210.0 5280.7 5356.1 529.6 536.3 540.2 568.3 574.9 3254.2 3423.1 3437.6 3446.1 3554.6 8215.7 8568.7 8794.2 8797.9 9058.6 9387.0 9395.8 9399.2 9408.1 9424.7 6819.2 7024.9 7113.3 7346.3 7375.7 4974.0 5049.5 5118.3 5237.8 5278.0 8479.0 8572.6 8654.5 8712.3 8712.8 8358.5 8685.7 8691.2 8725.6 8778.6 6372.9 6469.2 6521.6 6581.1 6591.7 7038.2 7051.8 7087.8 7106.4 7108.3 6429.9 6540.5 6563.8 6737.2 6794.0 6769.3 6820.5 6843.5 6892.0 6920.5 5962.3 6075.8 6169.4 6177.1 6187.6 4528.9 4570.7 4588.1 4675.9 4704.6 4469.2 4487.5 4560.5 4651.2 4702.7 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#4] prototype.html Cycles:180 Average:4370.87 Median:4899.40 stddev:1229.28 (25.1%) stddev-sans-first:1195.01 20:41:17 INFO - PROCESS | 20781 | Values: 344.9 351.1 357.6 365.8 373.1 4807.4 4831.8 5049.2 5059.9 5060.0 4842.2 4937.5 4987.7 5013.7 5028.0 3984.5 4038.9 4053.5 4055.1 4058.5 3829.3 3999.9 4039.2 4049.4 4053.8 3972.8 3986.4 3986.8 3993.4 4008.2 3987.3 3997.9 3999.0 3999.2 4010.4 3611.6 3624.4 3673.3 3673.6 3693.2 4633.5 4869.5 4896.4 4909.0 4934.1 4397.0 4408.5 4461.3 4469.5 4472.1 5209.7 5213.8 5226.4 5251.3 5262.2 3485.9 3683.7 3730.9 3733.3 3761.9 5335.4 5435.2 5443.5 5469.3 5512.3 4034.2 4061.5 4073.8 4077.7 4078.0 4186.8 4192.7 4194.8 4200.6 4225.2 4404.7 4634.6 4655.1 4669.2 4672.4 5229.6 5287.2 5290.3 5302.9 5320.9 1399.0 1418.4 1429.4 1430.0 1432.6 943.2 987.9 996.4 1011.3 1020.2 4466.4 4483.4 4511.8 4513.6 4519.6 5199.7 5224.6 5228.4 5228.6 5248.7 4291.6 4464.0 4509.2 4513.6 4549.0 2748.4 2749.2 2779.7 2789.8 2800.4 4841.9 4857.5 4902.4 4951.7 4953.3 5183.8 5350.1 5396.6 5408.9 5527.9 5046.0 5058.0 5062.2 5074.6 5081.7 5316.1 5398.9 5425.5 5443.8 5446.0 5170.5 5291.6 5298.2 5310.6 5318.9 4868.6 4993.9 5021.5 5035.5 5082.6 3711.6 3735.3 3738.4 3739.6 3747.5 5443.5 5450.9 5464.6 5467.2 5474.2 4992.3 5239.7 5247.3 5284.6 5335.9 5166.2 5240.5 5246.0 5248.0 5249.2 5128.2 5133.4 5156.0 5157.5 5158.7 4990.9 5249.2 5266.0 5274.0 5288.9 5166.7 5176.8 5176.9 5190.5 5225.5 20:41:17 INFO - PROCESS | 20781 | 20:41:17 INFO - PROCESS | 20781 | [#5] yui.html Cycles:180 Average:13885.47 Median:46125.54 stddev:16720.56 (36.3%) stddev-sans-first:16737.90 20:41:17 INFO - PROCESS | 20781 | Values: 644.2 656.4 712.6 734.9 763.4 11964.5 13069.6 13109.4 13188.7 13296.3 12165.2 12634.9 12744.5 12750.6 12763.1 796.4 812.7 814.2 815.0 815.5 781.5 797.2 804.6 805.8 807.0 834.9 844.4 846.5 848.2 849.6 841.2 842.0 846.9 849.5 852.4 618.3 620.5 621.0 621.5 621.8 12437.0 12642.6 12678.3 12683.4 13849.4 12730.9 12914.4 12928.9 13158.6 13333.3 10264.6 10399.7 10426.7 10465.1 10637.5 3091.2 3109.5 3252.8 3252.9 3256.9 60473.7 61381.0 61792.4 61845.0 63236.4 9521.7 9716.6 9836.7 9860.1 9967.6 18572.3 18587.3 18855.2 18904.3 19147.0 5785.3 5896.2 5899.2 5919.1 6165.3 3374.6 3422.3 3460.5 3461.2 3510.8 3064.7 3133.5 3144.9 3161.9 3201.8 500.1 504.1 504.6 518.9 520.7 613.9 627.2 632.3 632.7 633.1 8349.9 8452.9 8586.8 8950.1 9159.6 561.0 576.4 582.8 587.8 588.3 4064.3 4181.5 4224.3 4227.6 4324.0 55526.4 64027.0 64252.0 65548.0 65892.7 40284.8 42008.6 43271.8 44056.8 44306.1 45868.1 46383.0 46667.5 46680.8 49610.0 8198.8 9106.1 9246.2 9349.5 9402.6 33244.8 34039.0 34159.5 34445.5 35170.6 37998.4 39621.4 40257.2 40407.4 42425.8 10139.2 10167.8 10191.2 10195.7 10449.2 12291.4 12344.7 12594.9 12613.7 12849.5 11907.5 11955.8 12182.9 12220.8 12291.4 11794.9 11952.8 12205.2 12246.7 12645.6 10494.9 10743.5 11104.9 11105.7 11107.8 9443.3 9724.0 9779.8 9834.5 9959.7 7952.3 8764.3 8932.2 8956.7 9060.2 20:41:17 INFO - PROCESS | 20781 | -------- Summary: end -------- 20:41:17 INFO - PROCESS | 20781 | 20:41:18 INFO - TEST-INFO | 20781: exit 0 20:41:18 INFO - TEST-OK | dromaeo_css | took 1119731ms 20:41:18 INFO - TEST-START | kraken 20:41:18 INFO - operating with platform_type : linux_ 20:41:18 INFO - Initialising browser for kraken test... 20:41:18 INFO - TEST-INFO | started process 21155 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptoZLlJ/profile http://localhost:56772/getInfo.html) 20:41:21 INFO - PROCESS | 21155 | Iterating over profile /tmp/tmptoZLlJ/profile 20:41:22 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:22 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/places.sqlite-wal = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:22 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:22 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/content-prefs.sqlite = Wed Feb 22 2017 20:41:20 GMT-0800 (PST) 20:41:22 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:22 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/mimeTypes.rdf = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:22 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:22 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/compatibility.ini = Wed Feb 22 2017 20:41:19 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/extensions = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/directoryLinks.json = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | __metrics Screen width/height:1600/1200 20:41:23 INFO - PROCESS | 21155 | colorDepth:24 20:41:23 INFO - PROCESS | 21155 | Browser inner width/height: 1024/697 20:41:23 INFO - PROCESS | 21155 | __metrics 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/bookmarkbackups = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/cache2 = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/localstore.rdf = Wed Feb 22 2017 20:21:57 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/webappsstore.sqlite = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/places.sqlite = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/extensions.json = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/addons.json = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/safebrowsing = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/permissions.sqlite = Wed Feb 22 2017 20:41:19 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/key3.db = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/startupCache = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/pluginreg.dat = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | Stat failure 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/cert8.db = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/places.sqlite-shm = Wed Feb 22 2017 20:41:20 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/prefs.js = Wed Feb 22 2017 20:21:57 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/minidumps = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | Stat failure 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/.parentlock = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/extensions.ini = Wed Feb 22 2017 20:41:19 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/thumbnails = Wed Feb 22 2017 20:41:20 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/gmp = Wed Feb 22 2017 20:41:19 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/secmod.db = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | Stat failure 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/frequencyCap.json = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/crashes = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/user.js = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/blocklist.xml = Wed Feb 22 2017 20:41:18 GMT-0800 (PST) 20:41:23 INFO - PROCESS | 21155 | No birth date. Using mtime. 20:41:23 INFO - PROCESS | 21155 | Using date: /tmp/tmptoZLlJ/profile/storage.sqlite = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:23 ERROR - PROCESS | 21155 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:41:24 INFO - TEST-INFO | 21155: exit 0 20:41:24 INFO - Browser initialized. 20:41:24 INFO - Running cycle 1/1 for kraken test... 20:41:24 INFO - TEST-INFO | started process 21283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptoZLlJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:37 INFO - PROCESS | 21283 | RSS: Main: 154058752 20:41:37 INFO - PROCESS | 21283 | 20:41:39 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56772/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:40 INFO - PROCESS | 21283 | RSS: Main: 160010240 20:41:40 INFO - PROCESS | 21283 | 20:41:43 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56772/tests/kraken/driver.html?testName=audio-dft) 20:41:44 INFO - PROCESS | 21283 | RSS: Main: 158183424 20:41:44 INFO - PROCESS | 21283 | 20:41:47 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56772/tests/kraken/driver.html?testName=audio-fft) 20:41:47 INFO - PROCESS | 21283 | RSS: Main: 155660288 20:41:47 INFO - PROCESS | 21283 | 20:41:49 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56772/tests/kraken/driver.html?testName=audio-oscillator) 20:41:50 INFO - PROCESS | 21283 | RSS: Main: 155906048 20:41:50 INFO - PROCESS | 21283 | 20:41:52 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56772/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:53 INFO - PROCESS | 21283 | RSS: Main: 156299264 20:41:53 INFO - PROCESS | 21283 | 20:41:56 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56772/tests/kraken/driver.html?testName=imaging-darkroom) 20:41:57 INFO - PROCESS | 21283 | RSS: Main: 161484800 20:41:57 INFO - PROCESS | 21283 | 20:42:00 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56772/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:01 INFO - PROCESS | 21283 | RSS: Main: 157798400 20:42:01 INFO - PROCESS | 21283 | 20:42:04 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56772/tests/kraken/driver.html?testName=json-parse-financial) 20:42:04 INFO - PROCESS | 21283 | RSS: Main: 159010816 20:42:04 INFO - PROCESS | 21283 | 20:42:06 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56772/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:07 INFO - PROCESS | 21283 | RSS: Main: 159326208 20:42:07 INFO - PROCESS | 21283 | 20:42:09 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:09 INFO - PROCESS | 21283 | RSS: Main: 159760384 20:42:09 INFO - PROCESS | 21283 | 20:42:11 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:12 INFO - PROCESS | 21283 | RSS: Main: 159985664 20:42:12 INFO - PROCESS | 21283 | 20:42:15 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:16 INFO - PROCESS | 21283 | RSS: Main: 160436224 20:42:16 INFO - PROCESS | 21283 | 20:42:19 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:19 INFO - PROCESS | 21283 | RSS: Main: 163336192 20:42:19 INFO - PROCESS | 21283 | 20:42:21 INFO - PROCESS | 21283 | Cycle 1(1): loaded http://localhost:56772/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56772/tests/kraken/driver.html?testName=ai-astar) 20:42:22 INFO - PROCESS | 21283 | RSS: Main: 164151296 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | __start_tp_report 20:42:22 INFO - PROCESS | 21283 | _x_x_mozilla_page_load 20:42:22 INFO - PROCESS | 21283 | _x_x_mozilla_page_load_details 20:42:22 INFO - PROCESS | 21283 | |i|pagename|runs| 20:42:22 INFO - PROCESS | 21283 | |0;ai-astar;109;116;116;115;116;116;116;115;116;117 20:42:22 INFO - PROCESS | 21283 | |1;audio-beat-detection;155;127;130;168;129;133;128;130;164;130 20:42:22 INFO - PROCESS | 21283 | |2;audio-dft;164;168;169;169;174;163;166;165;163;166 20:42:22 INFO - PROCESS | 21283 | |3;audio-fft;76;78;76;119;79;76;77;77;100;79 20:42:22 INFO - PROCESS | 21283 | |4;audio-oscillator;85;83;105;86;85;84;98;84;84;113 20:42:22 INFO - PROCESS | 21283 | |5;imaging-gaussian-blur;113;114;114;114;114;114;114;115;113;114 20:42:22 INFO - PROCESS | 21283 | |6;imaging-darkroom;151;150;151;151;151;151;151;154;151;152 20:42:22 INFO - PROCESS | 21283 | |7;imaging-desaturate;96;96;96;97;96;96;97;96;96;96 20:42:22 INFO - PROCESS | 21283 | |8;json-parse-financial;65;65;66;67;67;66;65;65;66;66 20:42:22 INFO - PROCESS | 21283 | |9;json-stringify-tinderbox;66;65;65;66;66;66;66;66;68;66 20:42:22 INFO - PROCESS | 21283 | |10;stanford-crypto-aes;89;88;90;88;88;87;87;97;86;92 20:42:22 INFO - PROCESS | 21283 | |11;stanford-crypto-ccm;151;139;139;143;153;138;139;137;196;143 20:42:22 INFO - PROCESS | 21283 | |12;stanford-crypto-pbkdf2;188;189;191;185;189;188;186;189;193;181 20:42:22 INFO - PROCESS | 21283 | |13;stanford-crypto-sha256-iterative;72;76;71;71;73;74;70;70;74;72 20:42:22 INFO - PROCESS | 21283 | __end_tp_report 20:42:22 INFO - PROCESS | 21283 | __start_cc_report 20:42:22 INFO - PROCESS | 21283 | _x_x_mozilla_cycle_collect,902 20:42:22 INFO - PROCESS | 21283 | __end_cc_report 20:42:22 INFO - PROCESS | 21283 | __startTimestamp1487824942054__endTimestamp 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | ------- Summary: start ------- 20:42:22 INFO - PROCESS | 21283 | Number of tests: 14 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.25 (1.9%) stddev-sans-first:0.60 20:42:22 INFO - PROCESS | 21283 | Values: 109.0 116.0 116.0 115.0 116.0 116.0 116.0 115.0 116.0 117.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:131.50 stddev:16.21 (12.3%) stddev-sans-first:16.18 20:42:22 INFO - PROCESS | 21283 | Values: 155.0 127.0 130.0 168.0 129.0 133.0 128.0 130.0 164.0 130.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#2] audio-dft Cycles:10 Average:166.70 Median:167.00 stddev:3.40 (2.0%) stddev-sans-first:3.46 20:42:22 INFO - PROCESS | 21283 | Values: 164.0 168.0 169.0 169.0 174.0 163.0 166.0 165.0 163.0 166.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#3] audio-fft Cycles:10 Average:83.70 Median:77.00 stddev:14.36 (18.7%) stddev-sans-first:14.96 20:42:22 INFO - PROCESS | 21283 | Values: 76.0 78.0 76.0 119.0 79.0 76.0 77.0 77.0 100.0 79.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:84.50 stddev:10.73 (12.7%) stddev-sans-first:11.18 20:42:22 INFO - PROCESS | 21283 | Values: 85.0 83.0 105.0 86.0 85.0 84.0 98.0 84.0 84.0 113.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 20:42:22 INFO - PROCESS | 21283 | Values: 113.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 113.0 114.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 20:42:22 INFO - PROCESS | 21283 | Values: 151.0 150.0 151.0 151.0 151.0 151.0 151.0 154.0 151.0 152.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:42:22 INFO - PROCESS | 21283 | Values: 96.0 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 96.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 20:42:22 INFO - PROCESS | 21283 | Values: 65.0 65.0 66.0 67.0 67.0 66.0 65.0 65.0 66.0 66.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 20:42:22 INFO - PROCESS | 21283 | Values: 66.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 68.0 66.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:88.50 stddev:3.22 (3.6%) stddev-sans-first:3.42 20:42:22 INFO - PROCESS | 21283 | Values: 89.0 88.0 90.0 88.0 88.0 87.0 87.0 97.0 86.0 92.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:143.00 stddev:17.80 (12.4%) stddev-sans-first:18.84 20:42:22 INFO - PROCESS | 21283 | Values: 151.0 139.0 139.0 143.0 153.0 138.0 139.0 137.0 196.0 143.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.90 Median:189.00 stddev:3.31 (1.8%) stddev-sans-first:3.52 20:42:22 INFO - PROCESS | 21283 | Values: 188.0 189.0 191.0 185.0 189.0 188.0 186.0 189.0 193.0 181.0 20:42:22 INFO - PROCESS | 21283 | 20:42:22 INFO - PROCESS | 21283 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:72.50 stddev:1.95 (2.7%) stddev-sans-first:2.06 20:42:22 INFO - PROCESS | 21283 | Values: 72.0 76.0 71.0 71.0 73.0 74.0 70.0 70.0 74.0 72.0 20:42:22 INFO - PROCESS | 21283 | -------- Summary: end -------- 20:42:22 INFO - PROCESS | 21283 | 20:42:23 INFO - TEST-INFO | 21283: exit 0 20:42:23 INFO - TEST-OK | kraken | took 64805ms 20:42:23 INFO - SUITE-END | took 1184s 20:42:23 INFO - Completed test suite (00:19:44) 20:42:23 INFO - javascript benchmark 20:42:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6250.325373787505, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2474.4308808973933, 2549.898004079837, 2553.757393047662, 2557.4501482181327, 2572.2283315005484, 4395.318725597537, 4489.034857505636, 4523.954760452399, 4600.17196904557, 4623.7919293631785, 4397.032652816375, 4513.909721805562, 4555.362249285103, 4590.173768721629, 4597.333386658933, 4155.605217504348, 4189.937150942738, 4195.8741237762915, 4220.620144187023, 4290.099079193373, 4264.616184543391, 4268.935965960513, 4279.87160385189, 4302.784860756965, 4333.523312435629, 4076.6331030207125, 4100.753954762724, 4135.8966025849295, 4284.400183974246, 4314.26657468228, 4181.707280490336, 4205.411242426063, 4223.113146239293, 4254.765987870666, 4262.46719160105, 4174.70777045607, 4188.4136220929095, 4207.095474473003, 4223.028703398205, 4237.555056719031, 4339.544347843463, 4376.124775044972, 4512.12013657337, 4554.908901821978, 4580.358749775034, 4821.276808478721, 4840.878978025542, 4855.198892182786, 4871.415430148405, 4936.703797772145, 4894.0, 4899.681520701143, 4902.754862256908, 4904.337914381548, 4945.085159245551, 4479.283314669638, 4587.42657167854, 4589.747563883985, 4594.19601569724, 4630.930536041962, 4894.608431325485, 4928.581070608967, 4942.233953737176, 4953.6532442729385, 5029.622778291643, 4686.789094490756, 4723.031778485402, 4729.0, 4732.02993386355, 4732.479427262998, 4833.95166048335, 4837.032593481318, 4847.103285892051, 4878.341423907798, 4900.754962251873, 4682.321063445849, 4815.711057336571, 4821.662483626183, 4828.0, 4828.855134345975, 4706.482286948433, 4745.83389581363, 4784.545468180518, 4796.712197268175, 4850.175470170009, 4164.042270277853, 4192.769397683096, 4193.1404062167185, 4196.076863090116, 4228.260054490452, 3621.2576421833987, 3628.0748409155494, 3634.9818250908575, 3659.012066742018, 3662.7252956028406, 4144.8134833932545, 4177.498700156001, 4206.453161089039, 4217.915641687149, 4225.112726327444, 4871.658983871166, 4873.512648735098, 4877.0, 4930.087933732271, 4930.605551555867, 4230.111676547898, 4239.872803815891, 4244.0663054128045, 4248.001719595893, 4287.013986783058, 4292.656587472995, 4323.135372925428, 4398.75806830621, 4404.405405270311, 4431.711938723973, 4628.907421851477, 4648.65135114861, 4655.23188673865, 4676.368690226844, 4696.882577935579, 4876.073546026386, 4958.826441074545, 4959.20652695567, 4968.453470118357, 5001.999600079926, 4501.324801279834, 4532.1842068427995, 4538.72767633943, 4648.233041548242, 4659.161350956725, 4790.928136077892, 4841.15279826022, 4843.418789745253, 4851.636127290397, 4874.268859671078, 4894.828680996148, 4904.043711476228, 4904.288878112725, 4917.877053073702, 4930.975345123395, 4583.220852455157, 4607.838725644587, 4681.438227412732, 4687.296905464208, 4714.528547145258, 4717.646176536842, 4730.503297153887, 4737.715737055788, 4779.880502987453, 4780.067886762048, 4660.930086048644, 4684.391029166187, 4685.812567497262, 4720.551547602972, 4752.405949256343, 4661.067786442793, 4706.270528068155, 4720.881977950441, 4748.738819364969, 4804.807807687654, 4654.138984287918, 4657.254839225708, 4673.088747694167, 4704.670673052853, 4720.409948756405], "value": 4475.059497305624, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.126188004153, 528.4398537550197, 534.409477527332, 550.6668465578325, 560.2968274815106, 37948.810255948716, 39597.4060389094, 39936.0, 39941.400878986766, 39942.60057399425, 39762.80118599403, 40224.39663405044, 40305.19389612213, 40357.39463908036, 40708.389374159335, 7565.5460672359695, 7815.413843961697, 7817.335526480228, 7820.100688420819, 7856.939313192732, 9322.161005509503, 9565.713028609152, 9635.470049147294, 9725.221982241439, 9795.706128816111, 9441.716748497556, 9550.80898382032, 9561.139497445229, 9575.856362154538, 9581.616735330614, 9531.428114313128, 9536.094071063239, 9571.856422153673, 9581.041895810364, 9583.952080239555, 9422.246220302359, 9450.102240287231, 9495.90504094964, 9510.524473776353, 9510.904890951071, 33326.833365833256, 33733.662663373296, 33756.49365259523, 33756.662433375845, 33778.324433511436, 3267.954314417278, 3331.583552055993, 3349.4138525757894, 3397.4394224952853, 3398.371301309241, 25188.244352669448, 25257.368565786004, 25438.236852894443, 25634.0, 25835.483290334276, 3399.2351720862857, 3408.6080100788477, 3475.2354482013916, 3497.9825100874586, 3502.1419752160627, 233314.0, 234548.0, 234613.0, 235492.0, 236282.0, 39986.400203996964, 41139.1772164555, 47327.0, 47668.0, 47704.7614761924, 45103.0, 45719.0, 45933.35399566062, 46148.538514614425, 46176.3073553897, 12300.01599872008, 12319.63041108768, 12379.381030948416, 12387.690307742198, 12439.564615238423, 9326.626934922662, 9414.19979301767, 9431.104045115702, 9463.526823658789, 9478.289128315404, 15750.291236894367, 15812.446564370191, 15838.841611583966, 15905.761413578806, 15906.76139857903, 6422.197225346832, 6585.341465853472, 6639.468842492589, 6654.101696271038, 6656.733730650816, 744.7505085796319, 756.1191212237792, 760.3650951455486, 780.5862892666896, 781.3007358414301, 26316.342091447867, 27464.487718565648, 27602.309942251202, 27752.583711244348, 27798.583021254697, 886.7738726624669, 895.9552022398854, 898.0435835834814, 901.0808974845621, 902.7968707040994, 14038.929805350908, 15885.920570397075, 16490.422835200712, 16545.91727041357, 17230.0, 324125.0, 327148.0, 329113.0, 329453.0, 330005.0, 51904.0, 53030.0, 53161.0, 53237.2014419784, 53277.46722532802, 1178.6935396796894, 1186.4898093819568, 1223.1560223445972, 1223.8470191226097, 1231.3166192763103, 27213.455730884874, 27976.160715178576, 28637.427251455272, 28875.7112428873, 29062.709372906, 43529.12941741211, 44250.33624495571, 44310.335344970495, 44370.99999999871, 44503.77748111239, 292034.0, 294012.0, 294633.0, 294900.0, 295250.0, 31398.215044624067, 31827.0, 32158.517622235217, 32429.35141297208, 37294.0, 36809.44785828161, 36949.26101477902, 36950.0, 37966.30505424208, 38237.808810955765, 31572.210694732814, 31817.36365272728, 31953.680463196, 31954.52068219025, 33436.164095897795, 31407.528887066255, 31624.209394765316, 32001.6799832008, 32454.35091298208, 32713.0, 31675.366492670477, 31754.841225793723, 31799.0, 31852.68147318497, 32281.35437291288, 25761.742382575936, 27441.31396715098, 28946.27634309159, 29066.854665726536, 29192.708072919, 22029.228976985727, 22113.226037088625, 22433.551328973655, 22505.549889001803, 22509.43726406853], "value": 16984.6196228104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.6200170821491, 379.92211596622695, 395.8337498250734, 402.53105132520614, 418.8240938805701, 16062.357505699772, 17688.46934591961, 18096.819031809693, 18305.908470457663, 18343.724844127333, 16417.67164656706, 16513.752293715603, 16797.16014199288, 16809.15954202288, 18222.81777182228, 5058.7976480940715, 5265.499777521149, 5268.947310526884, 5274.709890955995, 5277.683338999653, 5123.718195499246, 5221.086309895753, 5345.4387289334645, 5383.865403364927, 5469.699166545858, 5385.461453854603, 5420.891582168354, 5422.83731488054, 5429.049916264657, 5437.32033495813, 5344.91982620259, 5394.083005888989, 5449.591280653947, 5452.536534394581, 5468.453154684539, 5312.654677445954, 5344.572434205294, 5345.919811202835, 5348.224507446398, 5356.0894647909945, 19418.22327106914, 20066.799332006784, 20180.49548761278, 20662.5867482651, 21249.893750531148, 2076.262926661029, 2150.6343921533376, 2150.7849215078368, 2154.396768904709, 2196.967045494319, 8962.910370896272, 9303.11620396068, 9475.147236748726, 9805.950970245103, 11176.0, 1591.1725902530734, 1593.1715507935924, 1594.1869646480263, 1611.8388161183905, 1617.3126421271031, 75138.0, 77108.22891771067, 78216.60891695562, 79511.0, 80930.0, 10395.32430392022, 10706.304090234109, 10750.838737418788, 10882.510287037103, 10935.586884492228, 15044.849551504563, 15119.0, 15129.848701513065, 15174.24128793556, 15243.542693719206, 6654.968479885625, 6922.619255940871, 7035.6482175891, 7111.395531379787, 7113.0753002109395, 6228.28374736902, 6276.121343011982, 6439.581427207217, 6460.063290822804, 6470.22357317125, 4496.303073023687, 4570.200214962369, 4585.426821647294, 4708.246680531099, 4764.976175119103, 1938.2925232290113, 1946.250693482991, 1973.8914359710066, 1997.340877510418, 2021.5047313408309, 374.31313539654496, 381.38596859056895, 386.5091334005797, 399.5804405374403, 776.3711393771064, 10869.402182880018, 10912.454377281105, 11042.723931901766, 11043.619547556555, 11656.533738650529, 395.1346551053184, 401.0495126550095, 404.6095517825313, 405.2401746724891, 415.28778145480646, 5887.76448942046, 5905.498032667271, 5918.852028699317, 5963.701814909237, 6047.0929360596265, 493824.0, 506136.0, 511607.0, 512072.0774772865, 512424.0, 81105.59447202727, 83649.58175209085, 84695.0, 85333.0, 86562.56718716492, 925.2181906289175, 957.6360982826482, 958.0515289862985, 960.0399600399601, 961.0485619237045, 16185.4335098271, 16208.67582648364, 16500.17499125015, 16785.66428671444, 16900.577485562466, 38999.41500877546, 39465.01337466471, 39671.2065758689, 40181.1963760729, 40781.0, 47939.28091078706, 48466.5153348462, 50540.0, 54326.185107222635, 58339.708301458224, 7876.383295416498, 7948.801279968047, 8101.675932962851, 8132.837343252983, 8271.33829293643, 10763.623273185402, 11828.70428239301, 11956.402179891145, 12107.33409662477, 12469.9448635085, 5569.104065885797, 10206.6427675034, 10349.068583827491, 10456.947715261374, 10570.312929659547, 6372.872542549216, 10120.443375614117, 10354.637587684394, 10472.738181545523, 10657.0, 9825.705228843146, 10067.395956242648, 10474.004969527883, 10852.077573406348, 11067.778644427228, 7918.604069796603, 8001.919980800118, 8114.959425203072, 8139.511629302261, 8408.159184081544, 9839.360441571274, 9968.750781230237, 10135.442550659785, 10328.328658637165, 10843.1867609928], "value": 7927.282034056398, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.3461703765158, 1353.8849197818186, 1367.5487089260546, 1369.678125640475, 1379.4620098161715, 7385.7414990475345, 7467.477276590627, 7658.119316278629, 7837.3338266247365, 7846.215378462158, 7706.344960678349, 7765.184655611164, 7810.414218933589, 7822.921770782291, 7860.842783144334, 2074.419162634461, 2108.5888251790907, 2117.6717608770587, 2120.957580848382, 2125.404886631738, 2089.9477513062143, 2130.1266480742897, 2132.3176583493287, 2133.4026472587702, 2136.6154092263387, 2068.5759419319, 2082.8437867159946, 2109.4409981354875, 2117.375374264596, 2119.5972765174643, 2098.58028394321, 2107.8102970732707, 2123.352377524854, 2124.745030596323, 2129.0525716056363, 1760.6918789211966, 1827.607064481143, 2010.5476267839765, 2013.2651582172548, 2117.6188286108495, 7768.805779855492, 7997.760067197935, 8003.559804210766, 8010.439269251155, 8010.439269251155, 4516.864494065183, 4645.349651048856, 4650.348951146842, 4652.325412815157, 4652.930206046912, 2904.2448963269703, 2916.0814343481734, 2950.27718209038, 2995.026616349673, 3025.3797971415806, 1101.4162493878257, 1135.7955567997758, 1743.4508129939156, 1805.3049575913237, 1908.1794828223858, 9989.700308990668, 10271.486425678686, 10318.43248621333, 10466.581336746522, 10596.152307815433, 4036.6367026967714, 4129.525104612948, 4136.172765446922, 4156.709030367845, 4175.791210439465, 8127.715529956423, 8161.143079976636, 8164.877526837102, 8193.344532437392, 8194.139615340304, 4604.217283061887, 4623.052274283764, 4627.95372046282, 4628.791704373311, 4641.559051890065, 4047.7976101195413, 4267.8079486423185, 4294.914101717948, 4313.568643135662, 4326.264535029052, 3194.6645602211433, 3258.3971965186515, 3300.35643049603, 3328.151321413024, 3340.8329583521213, 973.7030205787303, 975.317277905469, 1001.9699409017741, 1004.1514919892679, 1007.9848802267971, 812.8049268175595, 815.4454970620035, 816.5386556595505, 823.2138307915926, 829.8298848735994, 4989.301497790312, 5167.32062079818, 5209.973950130225, 5280.683159010472, 5356.116240820221, 529.5948599321522, 536.3135186960697, 540.1627477410077, 568.2527476368562, 574.873527823878, 3254.218987443044, 3423.127102588824, 3437.6390478999847, 3446.121239084068, 3554.59122200945, 8215.712450064339, 8568.657253709782, 8794.164554367326, 8797.868031979526, 9058.599471140153, 9387.014363491735, 9395.765105872271, 9399.24806015517, 9408.106229908148, 9424.717258482257, 6819.24988251292, 7024.929750702529, 7113.288671132846, 7346.302101300368, 7375.704971801068, 4974.035256073903, 5049.491175067479, 5118.334616499831, 5237.8428647140645, 5278.0, 8478.957605211934, 8572.614232359434, 8654.48073115615, 8712.303015758598, 8712.78218044554, 8358.498490090615, 8685.739427816923, 8691.174338437839, 8725.607347669496, 8778.648854045767, 6372.904406434, 6469.159009328757, 6521.64130972801, 6581.14445122131, 6591.670416479061, 7038.155421349471, 7051.753188638373, 7087.82280442993, 7106.431485481253, 7108.253633368422, 6429.935700643121, 6540.476761859135, 6563.835904102436, 6737.157855268091, 6793.966030170015, 6769.255381908085, 6820.488463365168, 6843.520953533404, 6891.965540172267, 6920.51556391068, 5962.25471816023, 6075.787347442995, 6169.444749972345, 6177.135201071764, 6187.566870319214, 4528.864134075983, 4570.657200710027, 4588.105319462807, 4675.9064818702755, 4704.5765881070865, 4469.195544801967, 4487.461504619467, 4560.452745670541, 4651.162790697707, 4702.694324868872], "value": 4020.4779287916695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.91721986723184, 351.09767896505974, 357.5941306616989, 365.84634453529515, 373.0952440332194, 4807.447143578481, 4831.758412079391, 5049.242613607956, 5059.873503162423, 5060.0, 4842.2010368289275, 4937.543424689716, 4987.700737955725, 5013.724245166514, 5028.019536190435, 3984.5417776955587, 4038.9394159087637, 4053.507093137714, 4055.128147448298, 4058.5129784425912, 3829.3107240696663, 3999.9000774399783, 4039.151778126582, 4049.3925911113274, 4053.756774593534, 3972.82122304497, 3986.4219688145204, 3986.760794352348, 3993.381025940984, 4008.2183974125055, 3987.262356464049, 3997.8800635980965, 3998.980005099985, 3999.2201520703475, 4010.3583426651594, 3611.620779818134, 3624.401973674367, 3673.2837096766143, 3673.5591728992426, 3693.242885208526, 4633.513481084505, 4869.5130486951375, 4896.363472748555, 4909.0, 4934.051049835295, 4396.978015109936, 4408.493023302329, 4461.286194208943, 4469.486009108961, 4472.127935052666, 5209.7134657593815, 5213.765380557884, 5226.398964119099, 5251.343582052244, 5262.158054711266, 3485.873617093571, 3683.7291134558536, 3730.8734021607693, 3733.253349330145, 3761.9247615047548, 5335.413104558495, 5435.239066530688, 5443.455654434564, 5469.261649677323, 5512.3385193777, 4034.2334956358786, 4061.5126184858004, 4073.796310184538, 4077.6941729370415, 4078.0, 4186.811593478301, 4192.664586833046, 4194.846417235285, 4200.6219440249915, 4225.154969006149, 4404.7357158570585, 4634.58288754007, 4655.11552804966, 4669.245145364183, 4672.369230153954, 5229.555487783505, 5287.233351164136, 5290.338707661543, 5302.893942121136, 5320.946790532045, 1398.985735341869, 1418.4184484361363, 1429.371076726237, 1430.0, 1432.5630682641895, 943.1747221181466, 987.9357841740264, 996.4270544436978, 1011.2870426349406, 1020.2297265564451, 4466.3523789050405, 4483.417155769729, 4511.819527218874, 4513.593776560126, 4519.615832654195, 5199.714015729096, 5224.582033437316, 5228.379202447253, 5228.607854410934, 5248.658837175648, 4291.592298731615, 4464.0, 4509.188346097669, 4513.593776560126, 4548.977255113703, 2748.40909204522, 2749.230215539652, 2779.680336761258, 2789.8326100434037, 2800.4399120176045, 4841.927371089437, 4857.489963553847, 4902.4362198346935, 4951.741502308012, 4953.281774142729, 5183.792648294026, 5350.08420410877, 5396.568274538027, 5408.864778380572, 5527.894421115712, 5045.9747701261995, 5057.974710126426, 5062.215356619729, 5074.579117847009, 5081.695098294114, 5316.122839731401, 5398.919016214839, 5425.457454254543, 5443.788164982521, 5445.972770136124, 5170.457102004386, 5291.550218231493, 5298.231756395378, 5310.601704872072, 5318.946810531846, 4868.610511159028, 4993.925091123709, 5021.472745361831, 5035.546800787946, 5082.644214905069, 3711.5917249102035, 3735.2716220336774, 3738.383166777451, 3739.6073412291316, 3747.526632422588, 5443.455654434525, 5450.890982180414, 5464.562834973113, 5467.234587157881, 5474.178873169057, 4992.327570263857, 5239.711815850165, 5247.291615631841, 5284.577233821208, 5335.946640533545, 5166.199239117943, 5240.4759524047295, 5245.977034478393, 5247.9737601311745, 5249.238860365302, 5128.153854613947, 5133.435322114639, 5156.0, 5157.484251574812, 5158.690478571298, 4990.875228119181, 5249.160134378481, 5265.973670131625, 5274.0, 5288.947110528846, 5166.689998600096, 5176.818811341585, 5176.948230517647, 5190.48095190493, 5225.503577160163], "value": 4000.094264902209, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.1947565543071, 656.3600489522713, 712.5831388637647, 734.8530293941211, 763.3816608547075, 11964.521419143235, 13069.607911762638, 13109.410076546554, 13188.670283242924, 13296.26870522122, 12165.209261398002, 12634.873651263508, 12744.490220391173, 12750.617481475547, 12763.106582539225, 796.4186144114785, 812.6789917982424, 814.2305521282391, 815.0463957170125, 815.5432957543765, 781.4529829119639, 797.1789057270992, 804.6057431858405, 805.7824387415394, 807.0315621254518, 834.8956380452444, 844.4426678392263, 846.5005646668465, 848.1687945813106, 849.6176720475811, 841.1882533355341, 842.0064324097563, 846.9110743371949, 849.477571293653, 852.40757673417, 618.2519151826295, 620.4756980351596, 621.0, 621.4624349937271, 621.8196722950339, 12436.951288940392, 12642.620721378373, 12678.302693351861, 12683.365831708377, 13849.376778045013, 12730.936345318307, 12914.41885115172, 12928.90104341132, 13158.605241842664, 13333.266670333127, 10264.640737574224, 10399.688009359656, 10426.739331516697, 10465.058144766932, 10637.46812659364, 3091.2117410060514, 3109.5024796032753, 3252.7560432967384, 3252.9349413011846, 3256.902292931216, 60473.697631512, 61381.0, 61792.382076179114, 61845.0, 63236.367636323965, 9521.714348569554, 9716.61133554664, 9836.655717049869, 9860.112589866947, 9967.55146018431, 18572.349967751063, 18587.34944276944, 18855.151518181716, 18904.338348157748, 19147.0, 5785.30576330843, 5896.233489646319, 5899.174115623816, 5919.112133180058, 6165.290991535941, 3374.611919629274, 3422.264213194175, 3460.463628137643, 3461.1866211440292, 3510.7717998330027, 3064.678208788045, 3133.5143052826847, 3144.8584813683437, 3161.873525059018, 3201.791883527563, 500.0748615062106, 504.13540777566647, 504.629097613254, 518.8988147311238, 520.7474374928157, 613.923259592551, 627.2159800249688, 632.3328888023087, 632.7026297640102, 633.1357696744003, 8349.874751878726, 8452.915470845213, 8586.828263434696, 8950.14973577509, 9159.587818548182, 560.9775608975677, 576.420697199312, 582.775631381921, 587.7766448749447, 588.3057991569902, 4064.349704047338, 4181.540030596632, 4224.302990006676, 4227.619514243733, 4323.956760432418, 55526.444735552934, 64027.0, 64252.0, 65548.0, 65892.67053664797, 40284.798576006935, 42008.57991420108, 43271.78364108222, 44056.77971610185, 44306.113877722906, 45868.08263834705, 46383.0, 46667.533324667, 46680.83297917579, 49610.0, 8198.836023279382, 9106.134917182857, 9246.214071803972, 9349.485778281987, 9402.576884040098, 33244.833775830964, 34039.0, 34159.487607685405, 34445.48331774975, 35170.64829351674, 37998.43002354912, 39621.40567891541, 40257.1948561033, 40407.39388909227, 42425.78787106045, 10139.2395570334, 10167.84748228792, 10191.184705223712, 10195.694129176136, 10449.16406687448, 12291.44688489038, 12344.691382715144, 12594.937025314815, 12613.68465788326, 12849.486020559072, 11907.46416411281, 11955.760884782081, 12182.939085304517, 12220.816687749868, 12291.385430728249, 11794.882051179378, 11952.820707689218, 12205.20666156697, 12246.693832654255, 12645.557405491132, 10494.947525262325, 10743.497748934073, 11104.944475277573, 11105.722356941142, 11107.833382499108, 9443.291753118681, 9724.02759724022, 9779.804403912025, 9834.459104749307, 9959.651412200828, 7952.296169569449, 8764.34267429958, 8932.240759535513, 8956.731298061068, 9060.184583387527], "value": 6152.352789073084, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 116.0, 115.0, 116.0, 116.0, 116.0, 115.0, 116.0, 117.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 127.0, 130.0, 168.0, 129.0, 133.0, 128.0, 130.0, 164.0, 130.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 169.0, 169.0, 174.0, 163.0, 166.0, 165.0, 163.0, 166.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 119.0, 79.0, 76.0, 77.0, 77.0, 100.0, 79.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 105.0, 86.0, 85.0, 84.0, 98.0, 84.0, 84.0, 113.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 151.0, 151.0, 151.0, 151.0, 154.0, 151.0, 152.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 67.0, 67.0, 66.0, 65.0, 65.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 68.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 88.0, 88.0, 87.0, 87.0, 97.0, 86.0, 92.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 139.0, 139.0, 143.0, 153.0, 138.0, 139.0, 137.0, 196.0, 143.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 189.0, 191.0, 185.0, 189.0, 188.0, 186.0, 189.0, 193.0, 181.0], "value": 187.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 71.0, 71.0, 73.0, 74.0, 70.0, 70.0, 74.0, 72.0], "value": 72.3, "unit": "score"}]}]} 20:42:23 INFO - Return code: 0 20:42:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:23 ERROR - # TBPL SUCCESS # 20:42:23 INFO - Running post-action listener: _resource_record_post_action 20:42:23 INFO - [mozharness: 2017-02-23 04:42:23.846960Z] Finished run-tests step (success) 20:42:23 INFO - Running post-run listener: _resource_record_post_run 20:42:23 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 361627648; Read time: 84; Write time: 203336 20:42:23 INFO - TinderboxPrint: CPU usage
13.5% 20:42:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 84 20:42:23 INFO - TinderboxPrint: I/O write bytes / time
361,627,648 / 203,336 20:42:23 INFO - TinderboxPrint: CPU idle
8,284.4 (86.5%) 20:42:23 INFO - TinderboxPrint: CPU user
1,233.5 (12.9%) 20:42:23 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 104943616; Read time: 0; Write time: 55192 20:42:23 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 256270336; Read time: 84; Write time: 148144 20:42:24 INFO - Running post-run listener: _upload_blobber_files 20:42:24 INFO - Blob upload gear active. 20:42:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:24 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 20:42:24 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'] 20:42:24 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 20:42:24 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:28 INFO - (blobuploader) - INFO - Done attempting. 20:42:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:29 INFO - (blobuploader) - INFO - Done attempting. 20:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:30 INFO - (blobuploader) - INFO - Done attempting. 20:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:31 INFO - (blobuploader) - INFO - Done attempting. 20:42:31 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:31 INFO - Return code: 0 20:42:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:31 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d66f610d5e7f6982ff8845c93da8a1c809907096444b0143e40a01daca7f3db4ce642201188076c97bb3535186311e1c7124f0127d8c35b8190dcf70b36c1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a615d084fa5b59114390c36d0d52de1a67bd185b5a3576c4ff79b2200aba461c857b28c3aefa671944be6a62b8dfa81ee8946973b16ea905c6a086c434fa20", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7518f75c4d86b116bc35367f0094d56797e1110a69f366a89188d09b4b557bf3f32a90b1f90213b1fb4e869e1e0685782f453eb962daa21bf1bf070cd8ac2b2c"} 20:42:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:31 INFO - Contents: 20:42:31 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0d66f610d5e7f6982ff8845c93da8a1c809907096444b0143e40a01daca7f3db4ce642201188076c97bb3535186311e1c7124f0127d8c35b8190dcf70b36c1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a615d084fa5b59114390c36d0d52de1a67bd185b5a3576c4ff79b2200aba461c857b28c3aefa671944be6a62b8dfa81ee8946973b16ea905c6a086c434fa20", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7518f75c4d86b116bc35367f0094d56797e1110a69f366a89188d09b4b557bf3f32a90b1f90213b1fb4e869e1e0685782f453eb962daa21bf1bf070cd8ac2b2c"} 20:42:31 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:31 INFO - Copying logs to upload dir... 20:42:31 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.158356 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-22 20:42:31.359939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:31.368901) ========= 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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 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/0d66f610d5e7f6982ff8845c93da8a1c809907096444b0143e40a01daca7f3db4ce642201188076c97bb3535186311e1c7124f0127d8c35b8190dcf70b36c1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a615d084fa5b59114390c36d0d52de1a67bd185b5a3576c4ff79b2200aba461c857b28c3aefa671944be6a62b8dfa81ee8946973b16ea905c6a086c434fa20", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7518f75c4d86b116bc35367f0094d56797e1110a69f366a89188d09b4b557bf3f32a90b1f90213b1fb4e869e1e0685782f453eb962daa21bf1bf070cd8ac2b2c"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011408 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/0d66f610d5e7f6982ff8845c93da8a1c809907096444b0143e40a01daca7f3db4ce642201188076c97bb3535186311e1c7124f0127d8c35b8190dcf70b36c1db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d9a615d084fa5b59114390c36d0d52de1a67bd185b5a3576c4ff79b2200aba461c857b28c3aefa671944be6a62b8dfa81ee8946973b16ea905c6a086c434fa20", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e1546158633e4e433212f9a58af076b03ee1dc290f9af821c17aaff1ea5f641f534ee23ae88d9d7f5f0ddaf56767a19b38f3023238e5144e54b7c4572111d51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7518f75c4d86b116bc35367f0094d56797e1110a69f366a89188d09b4b557bf3f32a90b1f90213b1fb4e869e1e0685782f453eb962daa21bf1bf070cd8ac2b2c"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:31.675895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:31.682635) ========= 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=cbe409c6de289db6ac707de3000001d4-1487821664.620203-613739209 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:31.878296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:31.878694) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:31.879079) ========= ========= Total master_lag: 0.64 =========