builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1487820366.08 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.080160) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.080748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.081030) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.098343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.098710) ========= 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-1487820343.228726-702627981 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 19:26:06-- 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.4M=0s 2017-02-22 19:26:06 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091806 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.204508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.204826) ========= 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-1487820343.228726-702627981 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020428 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:06.308190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 19:26:06.308524) ========= 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-1487820343.228726-702627981 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 19:26:06,822 truncating revision to first 12 chars 2017-02-22 19:26:06,822 Setting DEBUG logging. 2017-02-22 19:26:06,822 attempt 1/10 2017-02-22 19:26:06,822 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 19:26:08,185 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.663393 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 19:26:07.984684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:07.985001) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:07.985445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:07.985706) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:07.996663) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-22 19:26:07.997063) ========= /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-1487820343.228726-702627981 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:26:08 INFO - MultiFileLogger online at 20170222 19:26:08 in /builds/slave/test 19:26:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:26:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:08 INFO - {'append_to_log': False, 19:26:08 INFO - 'base_work_dir': '/builds/slave/test', 19:26:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:26:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:08 INFO - 'branch': 'Firefox-Non-PGO', 19:26:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:08 INFO - 'config_files': ('talos/linux_config.py',), 19:26:08 INFO - 'default_actions': ('clobber', 19:26:08 INFO - 'read-buildbot-config', 19:26:08 INFO - 'download-and-extract', 19:26:08 INFO - 'populate-webroot', 19:26:08 INFO - 'create-virtualenv', 19:26:08 INFO - 'install', 19:26:08 INFO - 'run-tests'), 19:26:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:08 INFO - 'download_minidump_stackwalk': True, 19:26:08 INFO - 'download_symbols': 'ondemand', 19:26:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:08 INFO - 'gecko_profile': False, 19:26:08 INFO - 'gecko_profile_interval': 0, 19:26:08 INFO - 'installer_path': 'installer.exe', 19:26:08 INFO - 'log_level': 'info', 19:26:08 INFO - 'log_name': 'talos', 19:26:08 INFO - 'log_to_console': True, 19:26:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:26:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:26:08 INFO - 'opt_config_files': (), 19:26:08 INFO - 'pip_index': False, 19:26:08 INFO - 'suite': 'dromaeojs-e10s', 19:26:08 INFO - 'system_bits': '32', 19:26:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:08 INFO - 'title': 'talos-linux64-ix-054', 19:26:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:08 INFO - 'use_talos_json': True, 19:26:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:08 INFO - 'work_dir': 'build'} 19:26:08 INFO - [mozharness: 2017-02-23 03:26:08.613133Z] Running clobber step. 19:26:08 INFO - Running pre-action listener: _resource_record_pre_action 19:26:08 INFO - Running main action method: clobber 19:26:08 INFO - rmtree: /builds/slave/test/build 19:26:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:10 INFO - Running post-action listener: _resource_record_post_action 19:26:10 INFO - [mozharness: 2017-02-23 03:26:10.038165Z] Finished clobber step (success) 19:26:10 INFO - [mozharness: 2017-02-23 03:26:10.038262Z] Running read-buildbot-config step. 19:26:10 INFO - Running pre-action listener: _resource_record_pre_action 19:26:10 INFO - Running main action method: read_buildbot_config 19:26:10 INFO - Using buildbot properties: 19:26:10 INFO - { 19:26:10 INFO - "project": "", 19:26:10 INFO - "product": "firefox", 19:26:10 INFO - "who": "kwierso@gmail.com", 19:26:10 INFO - "installer_path": "public/build/target.tar.bz2", 19:26:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:26:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:26:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 19:26:10 INFO - "stage_platform": "linux64", 19:26:10 INFO - "basedir": "/builds/slave/test", 19:26:10 INFO - "buildnumber": 688, 19:26:10 INFO - "platform": "ubuntu64_hw", 19:26:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:26:10 INFO - "slavebuilddir": "test", 19:26:10 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 19:26:10 INFO - "branch": "mozilla-central", 19:26:10 INFO - "script_repo_revision": "production", 19:26:10 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 19:26:10 INFO - "slavename": "talos-linux64-ix-054", 19:26:10 INFO - "repo_path": "mozilla-central" 19:26:10 INFO - } 19:26:10 INFO - Finding installer, test and symbols from parent task. 19:26:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:10 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 19:26:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 19:26:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:11 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 19:26:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 19:26:11 INFO - Running post-action listener: _resource_record_post_action 19:26:11 INFO - [mozharness: 2017-02-23 03:26:11.434583Z] Finished read-buildbot-config step (success) 19:26:11 INFO - [mozharness: 2017-02-23 03:26:11.434800Z] Running download-and-extract step. 19:26:11 INFO - Running pre-action listener: _resource_record_pre_action 19:26:11 INFO - Running main action method: download_and_extract 19:26:11 INFO - mkdir: /builds/slave/test/build/tests 19:26: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')]} 19:26:11 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 19:26:11 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:26:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:26:12 INFO - Downloaded 896 bytes. 19:26:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:26:12 INFO - Using the following test package requirements: 19:26:12 INFO - {u'common': [u'target.common.tests.zip'], 19:26:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:26:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:26:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:26:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:26:12 INFO - u'mozbase': [u'target.common.tests.zip'], 19:26:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:26:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:26:12 INFO - u'web-platform': [u'target.common.tests.zip', 19:26:12 INFO - u'target.web-platform.tests.zip'], 19:26:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:26:12 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:26:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:12 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:13 INFO - Expected file size: 57165889 19:26:13 INFO - Obtained file size: 57165889 19:26:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:26:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:22 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:23 INFO - Expected file size: 57165889 19:26:23 INFO - Obtained file size: 57165889 19:26:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 19:26:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:26:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 19:26:35 INFO - Expected file size: 14404319 19:26:35 INFO - Obtained file size: 14404319 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:35 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:35 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:26:36 INFO - Downloaded 61547669 bytes. 19:26:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:36 INFO - mkdir: /builds/slave/test/properties 19:26:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:36 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:36 INFO - Contents: 19:26:36 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:36 INFO - Running post-action listener: _resource_record_post_action 19:26:36 INFO - Running post-action listener: set_extra_try_arguments 19:26:36 INFO - [mozharness: 2017-02-23 03:26:36.872363Z] Finished download-and-extract step (success) 19:26:36 INFO - [mozharness: 2017-02-23 03:26:36.872459Z] Running populate-webroot step. 19:26:36 INFO - Running pre-action listener: _resource_record_pre_action 19:26:36 INFO - Running main action method: populate_webroot 19:26:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:36 INFO - '--tppagecycles', 19:26:36 INFO - '7'], 19:26:36 INFO - 'tests': ['tsvgm']}}, 19:26:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['damp', 'tps']}, 19:26:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'tests': ['damp', 'tps']}, 19:26:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['dromaeo_dom']}, 19:26:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['basic_compositor_video']}, 19:26:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['a11yr', 19:26:36 INFO - 'ts_paint', 19:26:36 INFO - 'tpaint', 19:26:36 INFO - 'sessionrestore', 19:26:36 INFO - 'sessionrestore_no_auto_restore', 19:26:36 INFO - 'tabpaint']}, 19:26:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['tsvgx', 19:26:36 INFO - 'tsvgr_opacity', 19:26:36 INFO - 'tart', 19:26:36 INFO - 'tscrollx', 19:26:36 INFO - 'cart', 19:26:36 INFO - 'tsvg_static']}, 19:26:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:36 INFO - 'tsvgr_opacity', 19:26:36 INFO - 'tart', 19:26:36 INFO - 'tscrollx', 19:26:36 INFO - 'cart', 19:26:36 INFO - 'tsvg_static']}, 19:26:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'talos_options': ['--disable-e10s'], 19:26:36 INFO - 'tests': ['tp5o']}, 19:26:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:26:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'talos_options': ['--disable-e10s', 19:26:36 INFO - '--xperf_path', 19:26:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:36 INFO - 'tests': ['tp5n']}, 19:26:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:26:36 INFO - 'talos_options': ['--xperf_path', 19:26:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:36 INFO - 'tests': ['tp5n']}}, 19:26:36 INFO - 'talos.zip': {'path': '', 19:26:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:36 INFO - Running post-action listener: _resource_record_post_action 19:26:36 INFO - [mozharness: 2017-02-23 03:26:36.885864Z] Finished populate-webroot step (success) 19:26:36 INFO - [mozharness: 2017-02-23 03:26:36.885954Z] Running create-virtualenv step. 19:26:36 INFO - Running pre-action listener: _resource_record_pre_action 19:26:36 INFO - Running main action method: create_virtualenv 19:26:36 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:26:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:36 INFO - Using real prefix '/usr' 19:26:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:37 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:39 INFO - Installing pip.................done. 19:26:39 INFO - Return code: 0 19:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:26:39 INFO - Reading from file tmpfile_stdout 19:26:39 INFO - Output received: 19:26:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:26:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:39 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:26:39 INFO - Using env: {'DISPLAY': ':0', 19:26:39 INFO - 'HOME': '/home/cltbld', 19:26:39 INFO - 'LANG': 'en_US.UTF-8', 19:26:39 INFO - 'LANGUAGE': 'en_US:en', 19:26:39 INFO - 'LOGNAME': 'cltbld', 19:26:39 INFO - 'MAIL': '/var/mail/cltbld', 19:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:39 INFO - 'MOZ_NO_REMOTE': '1', 19:26:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:39 INFO - 'NO_EM_RESTART': '1', 19:26:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:39 INFO - 'PWD': '/builds/slave/test', 19:26:39 INFO - 'SHELL': '/bin/bash', 19:26:39 INFO - 'SHLVL': '1', 19:26:39 INFO - 'TERM': 'linux', 19:26:39 INFO - 'TMOUT': '86400', 19:26:39 INFO - 'USER': 'cltbld', 19:26:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:39 INFO - '_': '/tools/buildbot/bin/python'} 19:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:26:39 INFO - Cleaning up... 19:26:39 INFO - Return code: 0 19:26:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:39 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:26:39 INFO - Using env: {'DISPLAY': ':0', 19:26:39 INFO - 'HOME': '/home/cltbld', 19:26:39 INFO - 'LANG': 'en_US.UTF-8', 19:26:39 INFO - 'LANGUAGE': 'en_US:en', 19:26:39 INFO - 'LOGNAME': 'cltbld', 19:26:39 INFO - 'MAIL': '/var/mail/cltbld', 19:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:39 INFO - 'MOZ_NO_REMOTE': '1', 19:26:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:39 INFO - 'NO_EM_RESTART': '1', 19:26:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:39 INFO - 'PWD': '/builds/slave/test', 19:26:39 INFO - 'SHELL': '/bin/bash', 19:26:39 INFO - 'SHLVL': '1', 19:26:39 INFO - 'TERM': 'linux', 19:26:39 INFO - 'TMOUT': '86400', 19:26:39 INFO - 'USER': 'cltbld', 19:26:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:39 INFO - '_': '/tools/buildbot/bin/python'} 19:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:40 INFO - Downloading/unpacking psutil>=3.1.1 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:42 INFO - Installing collected packages: psutil 19:26:42 INFO - Running setup.py install for psutil 19:26:42 INFO - building 'psutil._psutil_linux' extension 19:26:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:26:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:26:42 INFO - building 'psutil._psutil_posix' extension 19:26:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:26:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:26:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:42 INFO - Successfully installed psutil 19:26:42 INFO - Cleaning up... 19:26:42 INFO - Return code: 0 19:26:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:26:42 INFO - Using env: {'DISPLAY': ':0', 19:26:42 INFO - 'HOME': '/home/cltbld', 19:26:42 INFO - 'LANG': 'en_US.UTF-8', 19:26:42 INFO - 'LANGUAGE': 'en_US:en', 19:26:42 INFO - 'LOGNAME': 'cltbld', 19:26:42 INFO - 'MAIL': '/var/mail/cltbld', 19:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:42 INFO - 'MOZ_NO_REMOTE': '1', 19:26:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:42 INFO - 'NO_EM_RESTART': '1', 19:26:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:42 INFO - 'PWD': '/builds/slave/test', 19:26:42 INFO - 'SHELL': '/bin/bash', 19:26:42 INFO - 'SHLVL': '1', 19:26:42 INFO - 'TERM': 'linux', 19:26:42 INFO - 'TMOUT': '86400', 19:26:42 INFO - 'USER': 'cltbld', 19:26:42 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:42 INFO - '_': '/tools/buildbot/bin/python'} 19:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:45 INFO - Installing collected packages: mozsystemmonitor 19:26:45 INFO - Running setup.py install for mozsystemmonitor 19:26:45 INFO - Successfully installed mozsystemmonitor 19:26:45 INFO - Cleaning up... 19:26:45 INFO - Return code: 0 19:26:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:45 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:26:45 INFO - Using env: {'DISPLAY': ':0', 19:26:45 INFO - 'HOME': '/home/cltbld', 19:26:45 INFO - 'LANG': 'en_US.UTF-8', 19:26:45 INFO - 'LANGUAGE': 'en_US:en', 19:26:45 INFO - 'LOGNAME': 'cltbld', 19:26:45 INFO - 'MAIL': '/var/mail/cltbld', 19:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:45 INFO - 'MOZ_NO_REMOTE': '1', 19:26:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:45 INFO - 'NO_EM_RESTART': '1', 19:26:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:45 INFO - 'PWD': '/builds/slave/test', 19:26:45 INFO - 'SHELL': '/bin/bash', 19:26:45 INFO - 'SHLVL': '1', 19:26:45 INFO - 'TERM': 'linux', 19:26:45 INFO - 'TMOUT': '86400', 19:26:45 INFO - 'USER': 'cltbld', 19:26:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:45 INFO - '_': '/tools/buildbot/bin/python'} 19:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:45 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:47 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:47 INFO - warning: no files found matching '*.txt' 19:26:47 INFO - no previously-included directories found matching 'build' 19:26:47 INFO - no previously-included directories found matching 'dist' 19:26:47 INFO - no previously-included directories found matching '.git*' 19:26:47 INFO - Installing collected packages: jsonschema, functools32 19:26:47 INFO - Running setup.py install for functools32 19:26:47 INFO - warning: no files found matching '*.txt' 19:26:47 INFO - no previously-included directories found matching 'build' 19:26:47 INFO - no previously-included directories found matching 'dist' 19:26:47 INFO - no previously-included directories found matching '.git*' 19:26:47 INFO - Successfully installed jsonschema functools32 19:26:47 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:26:47 INFO - Using env: {'DISPLAY': ':0', 19:26:47 INFO - 'HOME': '/home/cltbld', 19:26:47 INFO - 'LANG': 'en_US.UTF-8', 19:26:47 INFO - 'LANGUAGE': 'en_US:en', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MAIL': '/var/mail/cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SHLVL': '1', 19:26:47 INFO - 'TERM': 'linux', 19:26:47 INFO - 'TMOUT': '86400', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - '_': '/tools/buildbot/bin/python'} 19:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:48 INFO - Cleaning up... 19:26:48 INFO - Return code: 0 19:26:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:48 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:26:48 INFO - Using env: {'DISPLAY': ':0', 19:26:48 INFO - 'HOME': '/home/cltbld', 19:26:48 INFO - 'LANG': 'en_US.UTF-8', 19:26:48 INFO - 'LANGUAGE': 'en_US:en', 19:26:48 INFO - 'LOGNAME': 'cltbld', 19:26:48 INFO - 'MAIL': '/var/mail/cltbld', 19:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:48 INFO - 'MOZ_NO_REMOTE': '1', 19:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:48 INFO - 'NO_EM_RESTART': '1', 19:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:48 INFO - 'PWD': '/builds/slave/test', 19:26:48 INFO - 'SHELL': '/bin/bash', 19:26:48 INFO - 'SHLVL': '1', 19:26:48 INFO - 'TERM': 'linux', 19:26:48 INFO - 'TMOUT': '86400', 19:26:48 INFO - 'USER': 'cltbld', 19:26:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:48 INFO - '_': '/tools/buildbot/bin/python'} 19:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:48 INFO - Downloading/unpacking blobuploader==1.2.4 19:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:50 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:51 INFO - Downloading docopt-0.6.1.tar.gz 19:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:51 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:51 INFO - Running setup.py install for blobuploader 19:26:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:51 INFO - Running setup.py install for requests 19:26:51 INFO - Running setup.py install for docopt 19:26:51 INFO - Successfully installed blobuploader requests docopt 19:26:51 INFO - Cleaning up... 19:26:51 INFO - Return code: 0 19:26:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:51 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:51 INFO - Using env: {'DISPLAY': ':0', 19:26:51 INFO - 'HOME': '/home/cltbld', 19:26:51 INFO - 'LANG': 'en_US.UTF-8', 19:26:51 INFO - 'LANGUAGE': 'en_US:en', 19:26:51 INFO - 'LOGNAME': 'cltbld', 19:26:51 INFO - 'MAIL': '/var/mail/cltbld', 19:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:51 INFO - 'MOZ_NO_REMOTE': '1', 19:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:51 INFO - 'NO_EM_RESTART': '1', 19:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:51 INFO - 'PWD': '/builds/slave/test', 19:26:51 INFO - 'SHELL': '/bin/bash', 19:26:51 INFO - 'SHLVL': '1', 19:26:51 INFO - 'TERM': 'linux', 19:26:51 INFO - 'TMOUT': '86400', 19:26:51 INFO - 'USER': 'cltbld', 19:26:51 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:51 INFO - '_': '/tools/buildbot/bin/python'} 19:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:52 INFO - Running setup.py (path:/tmp/pip-WghGfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:52 INFO - Running setup.py (path:/tmp/pip-PhQkz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:52 INFO - Running setup.py (path:/tmp/pip-VOqRIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:52 INFO - Running setup.py (path:/tmp/pip-XbGLgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:52 INFO - Running setup.py (path:/tmp/pip-Ko0Ife-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:52 INFO - Running setup.py (path:/tmp/pip-5e8oqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:52 INFO - Running setup.py (path:/tmp/pip-uVOkzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:52 INFO - Running setup.py (path:/tmp/pip-xwYSbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:52 INFO - Running setup.py (path:/tmp/pip-BSBiAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:52 INFO - Running setup.py (path:/tmp/pip-Zq_Wqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:52 INFO - Running setup.py (path:/tmp/pip-axOOvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:52 INFO - Running setup.py (path:/tmp/pip-AZNUCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:52 INFO - Running setup.py (path:/tmp/pip-cIknq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:52 INFO - Running setup.py (path:/tmp/pip-18BZ40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:53 INFO - Running setup.py (path:/tmp/pip-4lsNpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:53 INFO - Running setup.py (path:/tmp/pip-P0BTT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:53 INFO - Running setup.py (path:/tmp/pip-mxHuot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:53 INFO - Running setup.py install for manifestparser 19:26:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:53 INFO - Running setup.py install for mozcrash 19:26:53 INFO - Running setup.py install for mozdebug 19:26:53 INFO - Running setup.py install for mozdevice 19:26:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:53 INFO - Running setup.py install for mozfile 19:26:53 INFO - Running setup.py install for mozhttpd 19:26:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:53 INFO - Running setup.py install for mozinfo 19:26:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:53 INFO - Running setup.py install for mozInstall 19:26:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Running setup.py install for mozleak 19:26:54 INFO - Running setup.py install for mozlog 19:26:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Running setup.py install for moznetwork 19:26:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Running setup.py install for mozprocess 19:26:54 INFO - Running setup.py install for mozprofile 19:26:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Running setup.py install for mozrunner 19:26:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:54 INFO - Running setup.py install for mozscreenshot 19:26:54 INFO - Running setup.py install for moztest 19:26:54 INFO - Running setup.py install for mozversion 19:26:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:55 INFO - Cleaning up... 19:26:55 INFO - Return code: 0 19:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:55 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:55 INFO - Using env: {'DISPLAY': ':0', 19:26:55 INFO - 'HOME': '/home/cltbld', 19:26:55 INFO - 'LANG': 'en_US.UTF-8', 19:26:55 INFO - 'LANGUAGE': 'en_US:en', 19:26:55 INFO - 'LOGNAME': 'cltbld', 19:26:55 INFO - 'MAIL': '/var/mail/cltbld', 19:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:55 INFO - 'MOZ_NO_REMOTE': '1', 19:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:55 INFO - 'NO_EM_RESTART': '1', 19:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:55 INFO - 'PWD': '/builds/slave/test', 19:26:55 INFO - 'SHELL': '/bin/bash', 19:26:55 INFO - 'SHLVL': '1', 19:26:55 INFO - 'TERM': 'linux', 19:26:55 INFO - 'TMOUT': '86400', 19:26:55 INFO - 'USER': 'cltbld', 19:26:55 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:55 INFO - '_': '/tools/buildbot/bin/python'} 19:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:55 INFO - Running setup.py (path:/tmp/pip-cGjBmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:55 INFO - Running setup.py (path:/tmp/pip-aKUo9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:55 INFO - Running setup.py (path:/tmp/pip-WccN45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:55 INFO - Running setup.py (path:/tmp/pip-K4lxd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:55 INFO - Running setup.py (path:/tmp/pip-1YrlGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:55 INFO - Running setup.py (path:/tmp/pip-THHwUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:55 INFO - Running setup.py (path:/tmp/pip-k2JRlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:55 INFO - Running setup.py (path:/tmp/pip-M4Fre_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:55 INFO - Running setup.py (path:/tmp/pip-YmAIrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:55 INFO - Running setup.py (path:/tmp/pip-vdxcQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:56 INFO - Running setup.py (path:/tmp/pip-M_3Nj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:56 INFO - Running setup.py (path:/tmp/pip-zf7Lpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:56 INFO - Running setup.py (path:/tmp/pip-TAACBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:56 INFO - Running setup.py (path:/tmp/pip-h8ha0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:56 INFO - Running setup.py (path:/tmp/pip-YMB6pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:56 INFO - Running setup.py (path:/tmp/pip-lDdCoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:56 INFO - Running setup.py (path:/tmp/pip-RCjFBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:26:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:58 INFO - Downloading blessings-1.6.tar.gz 19:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:58 INFO - Installing collected packages: blessings 19:26:58 INFO - Running setup.py install for blessings 19:26:58 INFO - Successfully installed blessings 19:26:58 INFO - Cleaning up... 19:26:58 INFO - Return code: 0 19:26:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:59 INFO - Reading from file tmpfile_stdout 19:26:59 INFO - Current package versions: 19:26:59 INFO - argparse == 1.2.1 19:26:59 INFO - blessings == 1.6 19:26:59 INFO - blobuploader == 1.2.4 19:26:59 INFO - docopt == 0.6.1 19:26:59 INFO - functools32 == 3.2.3-2 19:26:59 INFO - jsonschema == 2.5.1 19:26:59 INFO - manifestparser == 1.1 19:26:59 INFO - mozInstall == 1.12 19:26:59 INFO - mozcrash == 1.0 19:26:59 INFO - mozdebug == 0.1 19:26:59 INFO - mozdevice == 0.49 19:26:59 INFO - mozfile == 1.2 19:26:59 INFO - mozhttpd == 0.7 19:26:59 INFO - mozinfo == 0.9 19:26:59 INFO - mozleak == 0.1 19:26:59 INFO - mozlog == 3.4 19:26:59 INFO - moznetwork == 0.27 19:26:59 INFO - mozprocess == 0.25 19:26:59 INFO - mozprofile == 0.28 19:26:59 INFO - mozrunner == 6.13 19:26:59 INFO - mozscreenshot == 0.1 19:26:59 INFO - mozsystemmonitor == 0.3 19:26:59 INFO - moztest == 0.8 19:26:59 INFO - mozversion == 1.4 19:26:59 INFO - psutil == 3.1.1 19:26:59 INFO - requests == 1.2.3 19:26:59 INFO - wsgiref == 0.1.2 19:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:26:59 INFO - Using env: {'DISPLAY': ':0', 19:26:59 INFO - 'HOME': '/home/cltbld', 19:26:59 INFO - 'LANG': 'en_US.UTF-8', 19:26:59 INFO - 'LANGUAGE': 'en_US:en', 19:26:59 INFO - 'LOGNAME': 'cltbld', 19:26:59 INFO - 'MAIL': '/var/mail/cltbld', 19:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:59 INFO - 'MOZ_NO_REMOTE': '1', 19:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:59 INFO - 'NO_EM_RESTART': '1', 19:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:59 INFO - 'PWD': '/builds/slave/test', 19:26:59 INFO - 'SHELL': '/bin/bash', 19:26:59 INFO - 'SHLVL': '1', 19:26:59 INFO - 'TERM': 'linux', 19:26:59 INFO - 'TMOUT': '86400', 19:26:59 INFO - 'USER': 'cltbld', 19:26:59 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:59 INFO - '_': '/tools/buildbot/bin/python'} 19:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:26:59 INFO - Cleaning up... 19:26:59 INFO - Return code: 0 19:26:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 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 0xf361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11d3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, '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-1487820343.228726-702627981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:26:59 INFO - Using env: {'DISPLAY': ':0', 19:26:59 INFO - 'HOME': '/home/cltbld', 19:26:59 INFO - 'LANG': 'en_US.UTF-8', 19:26:59 INFO - 'LANGUAGE': 'en_US:en', 19:26:59 INFO - 'LOGNAME': 'cltbld', 19:26:59 INFO - 'MAIL': '/var/mail/cltbld', 19:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:59 INFO - 'MOZ_NO_REMOTE': '1', 19:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:59 INFO - 'NO_EM_RESTART': '1', 19:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:59 INFO - 'PWD': '/builds/slave/test', 19:26:59 INFO - 'SHELL': '/bin/bash', 19:26:59 INFO - 'SHLVL': '1', 19:26:59 INFO - 'TERM': 'linux', 19:26:59 INFO - 'TMOUT': '86400', 19:26:59 INFO - 'USER': 'cltbld', 19:26:59 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:59 INFO - '_': '/tools/buildbot/bin/python'} 19:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:59 INFO - Cleaning up... 19:26:59 INFO - Return code: 0 19:26:59 INFO - Running post-action listener: _resource_record_post_action 19:26:59 INFO - Running post-action listener: _start_resource_monitoring 19:26:59 INFO - Starting resource monitoring. 19:26:59 INFO - [mozharness: 2017-02-23 03:26:59.555134Z] Finished create-virtualenv step (success) 19:26:59 INFO - [mozharness: 2017-02-23 03:26:59.555607Z] Running install step. 19:26:59 INFO - Running pre-action listener: _resource_record_pre_action 19:26:59 INFO - Running main action method: install 19:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:59 INFO - Reading from file tmpfile_stdout 19:26:59 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:59 INFO - Reading from file tmpfile_stdout 19:26:59 INFO - Output received: 19:26:59 INFO - Usage: mozinstall [options] installer 19:26:59 INFO - Options: 19:26:59 INFO - -h, --help show this help message and exit 19:26:59 INFO - -d DEST, --destination=DEST 19:26:59 INFO - Directory to install application into. [default: 19:26:59 INFO - "/builds/slave/test"] 19:26:59 INFO - --app=APP Application being installed. [default: firefox] 19:26:59 INFO - mkdir: /builds/slave/test/build/application 19:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:27:15 INFO - Reading from file tmpfile_stdout 19:27:15 INFO - Output received: 19:27:15 INFO - /builds/slave/test/build/application/firefox/firefox 19:27:15 INFO - Running post-action listener: _resource_record_post_action 19:27:15 INFO - [mozharness: 2017-02-23 03:27:15.366453Z] Finished install step (success) 19:27:15 INFO - [mozharness: 2017-02-23 03:27:15.366729Z] Running run-tests step. 19:27:15 INFO - Running pre-action listener: _resource_record_pre_action 19:27:15 INFO - Running main action method: run_tests 19:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:15 INFO - Python 2.7.3 19:27:15 INFO - Return code: 0 19:27:15 INFO - grabbing minidump binary from tooltool 19:27:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:15 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 0x11d1290>, '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 0x11d1b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d2040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:27:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:27:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:27:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:15 INFO - Return code: 0 19:27:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:27:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:15 INFO - ENV: RUST_BACKTRACE is now 1 19:27:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:27:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:15 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 19:27:15 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 19:27:15 INFO - Using env: {'DISPLAY': ':0', 19:27:15 INFO - 'HOME': '/home/cltbld', 19:27:15 INFO - 'LANG': 'en_US.UTF-8', 19:27:15 INFO - 'LANGUAGE': 'en_US:en', 19:27:15 INFO - 'LOGNAME': 'cltbld', 19:27:15 INFO - 'MAIL': '/var/mail/cltbld', 19:27:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:27:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:15 INFO - 'MOZ_NO_REMOTE': '1', 19:27:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:15 INFO - 'NO_EM_RESTART': '1', 19:27:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:15 INFO - 'PWD': '/builds/slave/test', 19:27:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:15 INFO - 'RUST_BACKTRACE': '1', 19:27:15 INFO - 'SHELL': '/bin/bash', 19:27:15 INFO - 'SHLVL': '1', 19:27:15 INFO - 'TERM': 'linux', 19:27:15 INFO - 'TMOUT': '86400', 19:27:15 INFO - 'USER': 'cltbld', 19:27:15 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1487820343.228726-702627981', 19:27:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:15 INFO - '_': '/tools/buildbot/bin/python'} 19:27:15 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 19:27:15 INFO - mozversion application_buildid: 20170221181700 19:27:15 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:15 INFO - mozversion application_display_name: Nightly 19:27:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:15 INFO - mozversion application_name: Firefox 19:27:15 INFO - mozversion application_remotingname: firefox 19:27:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:27:15 INFO - mozversion application_vendor: Mozilla 19:27:15 INFO - mozversion application_version: 54.0a1 19:27:15 INFO - mozversion platform_buildid: 20170221181700 19:27:15 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:27:15 INFO - mozversion platform_version: 54.0a1 19:27:15 INFO - using testdate: 1487820435 19:27:15 INFO - actual date: 1487820435 19:27:15 INFO - starting webserver on 'localhost:46815' 19:27:15 INFO - SUITE-START | Running 2 tests 19:27:15 INFO - TEST-START | dromaeo_css 19:27:15 INFO - operating with platform_type : linux_ 19:27:15 INFO - Initialising browser for dromaeo_css test... 19:27:15 INFO - TEST-INFO | started process 19387 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCh5Q4d/profile http://localhost:46815/getInfo.html) 19:27:18 INFO - PROCESS | 19387 | Iterating over profile /tmp/tmpCh5Q4d/profile 19:27:19 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:19 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/places.sqlite-wal = Wed Feb 22 2017 19:27:19 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/content-prefs.sqlite = Wed Feb 22 2017 19:27:17 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/mimeTypes.rdf = Wed Feb 22 2017 19:27:19 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | __metrics Screen width/height:1600/1200 19:27:20 INFO - PROCESS | 19387 | colorDepth:24 19:27:20 INFO - PROCESS | 19387 | Browser inner width/height: 1024/697 19:27:20 INFO - PROCESS | 19387 | __metrics 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/compatibility.ini = Wed Feb 22 2017 19:27:16 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/extensions = Wed Feb 22 2017 19:27:16 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/directoryLinks.json = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/bookmarkbackups = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/cache2 = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/localstore.rdf = Wed Feb 22 2017 19:26:35 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/webappsstore.sqlite = Wed Feb 22 2017 19:27:19 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/places.sqlite = Wed Feb 22 2017 19:27:20 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/extensions.json = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/addons.json = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/safebrowsing = Wed Feb 22 2017 19:27:20 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/permissions.sqlite = Wed Feb 22 2017 19:27:16 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/key3.db = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/startupCache = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/pluginreg.dat = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | Stat failure 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/cert8.db = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/places.sqlite-shm = Wed Feb 22 2017 19:27:17 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/prefs.js = Wed Feb 22 2017 19:26:35 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/minidumps = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | Stat failure 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/.parentlock = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/extensions.ini = Wed Feb 22 2017 19:27:16 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/thumbnails = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/gmp = Wed Feb 22 2017 19:27:16 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/secmod.db = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:27:19 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | Stat failure 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/frequencyCap.json = Wed Feb 22 2017 19:27:18 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/crashes = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/user.js = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/blocklist.xml = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:20 INFO - PROCESS | 19387 | No birth date. Using mtime. 19:27:20 INFO - PROCESS | 19387 | Using date: /tmp/tmpCh5Q4d/profile/storage.sqlite = Wed Feb 22 2017 19:27:19 GMT-0800 (PST) 19:27:21 ERROR - PROCESS | 19387 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:27:22 INFO - TEST-INFO | 19387: exit 0 19:27:22 INFO - Browser initialized. 19:27:22 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:22 INFO - TEST-INFO | started process 19513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCh5Q4d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:34 INFO - PROCESS | 19513 | RSS: Main: 153952256 19:27:34 INFO - PROCESS | 19513 | 19:30:37 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46815/tests/dromaeo/cssquery-ext.html) 19:30:39 INFO - PROCESS | 19513 | RSS: Main: 163684352 19:30:39 INFO - PROCESS | 19513 | 19:32:22 INFO - PROCESS | 19513 | ************************* 19:32:22 INFO - PROCESS | 19513 | A coding exception was thrown and uncaught in a Task. 19:32:22 INFO - PROCESS | 19513 | 19:32:22 ERROR - PROCESS | 19513 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:32:22 INFO - PROCESS | 19513 | Full stack: 19:32:22 INFO - PROCESS | 19513 | ************************* 19:33:41 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-ext.html (next: http://localhost:46815/tests/dromaeo/cssquery-jquery.html) 19:33:42 INFO - PROCESS | 19513 | RSS: Main: 158273536 19:33:42 INFO - PROCESS | 19513 | 19:36:44 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46815/tests/dromaeo/cssquery-mootools.html) 19:36:45 INFO - PROCESS | 19513 | RSS: Main: 165769216 19:36:45 INFO - PROCESS | 19513 | 19:39:47 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46815/tests/dromaeo/cssquery-prototype.html) 19:39:47 INFO - PROCESS | 19513 | RSS: Main: 158240768 19:39:47 INFO - PROCESS | 19513 | 19:42:50 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46815/tests/dromaeo/cssquery-yui.html) 19:42:51 INFO - PROCESS | 19513 | RSS: Main: 165658624 19:42:51 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | Cycle 1(1): loaded http://localhost:46815/tests/dromaeo/cssquery-yui.html (next: http://localhost:46815/tests/dromaeo/cssquery-dojo.html) 19:45:54 INFO - PROCESS | 19513 | RSS: Main: 159096832 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | __start_tp_report 19:45:54 INFO - PROCESS | 19513 | _x_x_mozilla_page_load 19:45:54 INFO - PROCESS | 19513 | _x_x_mozilla_page_load_details 19:45:54 INFO - PROCESS | 19513 | |i|pagename|runs| 19:45:54 INFO - PROCESS | 19513 | |0;dojo.html;2498.3129639349177;2545.7581529754666;2555.8977640894364;2571.0615625296773;2583.806214533909;4435.911281774363;4565.018521017981;4582.839600613975;4601.309803529468;4669.9066018679605;4453;4553.795079221435;4570.56579624936;4580.175568397687;4649.58153766161;4202.3486359614235;4253.978730106345;4282.850100246492;4283.67872409569;4350.738955662655;4279.293916503789;4308.741475511463;4339.696221264513;4347.913041739163;4368.475782906055;4099.344104943225;4127.772972486512;4130.318497447917;4223.957760422418;4285.507166675841;4156.085661154541;4185.121124563845;4196.664266858644;4225.260579398593;4237.894052648678;4147.9170416591805;4161.25097482453;4176.561461046607;4196.685248606336;4203.516595591485;4447.888802779924;4473.194824931511;4484.775761211927;4510.954890451087;4535.614472769818;4803.831865884677;4825.179719447702;4842.12841688496;4854.417469903598;4900.558949694509;4860.756962151878;4873.731944743037;4877.780499877514;4889;4928.186849169882;4495.662825288116;4569.520200378945;4578.587927086578;4582.564656357641;4608.700434471749;4925.088858561177;4984.925226121611;4985.351904252424;4987.925181122286;4996.875078123004;4763.2140696784645;4792.448868380181;4795.328653988445;4797.712137271775;4851.684640498356;4880.072786170616;4880.365552478155;4894;4894.95105048945;4911.45973942866;4823.662343635982;4857.1742803723455;4871.658983871095;4880.902381952412;4913.606911447076;4654.581087702123;4668.486466488684;4680.063987202573;4724.551167639069;4732.290156476487;4051.4530538376944;4062.471878655756;4063.2889244382113;4063.817128229237;4070.043539768152;3582.373084710165;3611.7832930024283;3632.182758879284;3641.617630148849;3690.538682664667;4237.025484138665;4248.681348898845;4251.787410629456;4275.38006988991;4285.871423857289;4720.527947205321;4881.585065269421;4907.435644900811;4907.656464047483;4929.605631549539;4138.937915931264;4243.936340954888;4281.079567892918;4291.592298731615;4325.480942286946;4441.467023957146;4443.200223959654;4448.110377924428;4454.28731402974;4456.286994080931;4599.218132917479;4607;4624.884377890579;4624.930626040545;4665.206914824488;4780.474147843804;4817;4852.126617208936;4884.389451318585;4891.413030436371;4576.931346029879;4579.656525760648;4581.908361832811;4633.652476064376;4633.837815676435;4918.262260660929;4926.359573255454;4930.235813448921;4939.827106051271;4944.060628480577;4756.476787553297;4798.568128868279;4826.107170173529;5009.373828271466;5061.367329083864;4697.084068606726;4726.787294571684;4731.526847315378;4773.570378665798;4789.7605119743175;4718.646101542467;4724.126036683225;4729.881752956204;4734.455537613081;4781.808727650855;4561.475430325422;4588.587027167571;4630.328602352707;4646.117237724821;4721.622270218306;4815.542523460265;4862.41651001882;4868.61051115917;4889.877753056202;4893.314935908905;4738.715677059249;4744.478107408251;4756.310335001474;4769.976150119227;4817.759112044314 19:45:54 INFO - PROCESS | 19513 | |1;ext.html;531.449949302472;533.1789044870899;552.8230966090847;559.7537083683178;621.9782307619234;34212.31575368494;38606.61393386065;38810.80594597023;38906.80546597267;38934.415983760264;39123.80438097812;39651;39664.80167599165;39664.80167599165;39682.40476392849;7606.353459955911;7772.145064042951;7795.844083118334;9205.585748641293;9520.571574279174;9301.395409298408;9358.532073396336;9416.62333506659;9447.385919915218;9475.431474111541;9432.575534100983;9459.574319155654;9460.81078378432;9487.667931622394;9577.329586928918;9495.90504094957;9498.572564234626;9541.904580954171;9551.952240238823;9557.426554406688;9098.545072746336;9099.408538444979;9108.134727200906;9130.589123489393;9144.314176436726;32922.50616240758;33182.17044573881;33363.6663633363;33426.665733342845;33458;3251.9358530386494;3332.950005749916;3341.7159541439005;3360.1599600099976;3387.6781705737917;24780;24892.751072489224;25040;25109.62335564968;25929.870350648314;3351.8324083795715;3382.915427114317;3384.1539615096226;3391.287829555796;3415.5389022481895;229579;231785;232190.9999999983;232281;232319;38228.235435291135;39149.2170156601;41506.1698766023;45855.5414445858;46335;49424.50575494199;49745.75127124341;49803.25295120576;49980.750096249285;50006;12323.50705971751;12495.250284982749;12508.562200322946;12550.874491255154;12602.55891043809;9443;9490.478023708762;9570.425774453555;9573;9612.471314077793;15455.459058932884;15523.45667901618;15635.374585016472;15653.530394088195;15681.588472817608;6437.678116094176;6645.136132302867;6654.735600235939;6657.367550082734;6665.166854143232;750.4784174998324;765.1583258415809;770.9036370453694;791.5962858941866;796.585775396802;26235;27038.324041898715;27362.589561156597;27381.86309068442;27449.45101097967;810.550144669714;886.1404437695425;916.5692124701379;925.5002298758595;933.8459154239474;14310.427582896567;14332.641683957776;14487.20320382368;14491.20298383599;14558.344874480674;327745;328947;329562;330534;330707;48154.518454815705;48420.51579484231;48671.513284867404;48749;48768.512314876396;1139.1342579639534;1173.530587764901;1186.988130118705;1190.6844686158174;1191.3090407563593;24944.625830612917;25106.35957786074;25808.418548395068;26068;26128.60807087933;40207;40311.1937761249;40382.596174037884;40440;40504.59495405008;265787;266111;266288;267576;267861;21045.789542104383;21594.244201452875;23902.402439939142;24025.879870600536;24510;35431;35489.82255088708;35556;40022.799886000386;40545.391819122146;30564;30994;31362.686373135977;31850.04449866415;32829.34341313112;30595.694043059284;30725.231869202555;31072.68927310698;31131.844340778152;31871.68128318687;30498.237544061576;30972.380552388375;31308.217294567818;31392.215194620316;31828.840855795574;27344.589831152152;28930.132096037152;29070.12789616315;29120.417591647623;29248.41503169967;22493.550128997;22638.886805565868;22919.65620515727;22939.88530057339;23082.53834923326 19:45:54 INFO - PROCESS | 19513 | |2;jquery.html;380.5072431201592;386.38178913738005;398.7647288100021;403.19763670296106;424.3592175814521;17341.913290433546;17950.102494875253;17972.101394930232;17996.370127045557;18544.72182917254;17613.7357939631;17674.64650706985;17878;18208.81791182088;18221.817781822177;5056.418511871127;5252.424272718188;5262.210668399733;5274.4198138204765;5385.703786291752;5507.063799154133;5636.7181640918;5655.0386434306065;5667.3482549506925;5674.914876276859;5454.53636440901;5540.695261760601;5651.660900345993;5657.20799088128;5659.688717120557;5596.916046259309;5673.914891276634;5686.687232202248;5688.402717714622;5690.430956904297;4867.2212446008825;5093.567046801027;5141.30592370029;5538.806141785019;5562.16567514872;12520.436580353906;12551.93724031374;12690.238585684796;12711.173773704772;20369.49076273105;2045.1819272291202;2135.540858715384;2172.945676358088;2184.857984231036;2215.4682876109778;12882.742345153045;13203.735925281537;13251;13717.0398072135;14253.287335633177;1583.6515966487354;1632.9265183066673;1653.5535405440585;1663.9168041597993;1664.0514906503297;72902.63548682275;78111;79673.60163199205;80085;82234.58882705608;10612.57549698019;10780.676579702622;10964.848690887502;11024.834627480594;11051.778964420666;15346.76979845281;15379.307931143125;15394.769078463609;15415.689666378483;15420.92289538545;6551.6069035858;6785.55893866907;6835.179778426621;6868.737349758824;6904.861902762017;5933.495652869468;6028.125921741411;6172.950598398283;6191.678120704736;6243.625382477066;4622.907541849211;4629.67592268538;4746.952530474651;4751.667383283206;4870.342503762017;1912.5792325688326;1993.601279744057;2004.8897310647762;2006.4181387397782;2040.704097905825;774.3418094619528;802.7832485228955;811.3874025111004;811.5009269299456;829.5769157729601;16778.74831877523;16809;17281.654366912844;17643.382481613084;17760.467185984184;800.7557694903111;806.6370133439975;816.269438852224;825.4098319701442;838.9916100839035;9468.384555003902;9751.756206094762;9827.361221520578;9885.357451765756;9907.603695852085;439807;446582;460435;461257;461888;86773.56613217019;89426;89501.10498895058;89781.55109224543;90242.54878725564;913.55235934393;917.2111983694018;943.1794338925042;944.0370821761765;944.1455482788087;14996.925015374853;15010.849891500946;15677.608059798597;15774.9211253943;15775.29011194476;36363;37598;37937;38312.61687383202;38466.80766596149;47752.5224747762;49161.508384915694;50536;50995.745021274655;51898;6957.930420695729;7512.098548174254;7616.619169041636;7779.494332868346;7907.762767116996;8786.428882122642;11714.88285117138;11822.172447928562;12228.510859565873;12301.892829645374;6366.840828979127;10288.639897603847;10435.165186784887;10742.086922611354;10795.40625265618;10127.949360253151;10194.847077293698;10436.53035613384;10996.615118470816;11047.226694131334;10291.897081029394;10292.948535257276;10434.895651043393;10441.738956526147;10932.890671093188;7782.455228133977;7821.96089019574;7940.444168908121;7957.244061814118;8028;9728.319017668695;10372.17022638172;10672;10708;11039.889601103887 19:45:54 INFO - PROCESS | 19513 | |3;mootools.html;1360.43541930099;1363.3932899859567;1365.255935515144;1368.8836448901843;1382.6128683968486;7258.891116633248;7525.698972041126;7656.157822639505;7661.502002369842;7670.348020418264;7686.116096648873;7809.219078092188;7811.257930496594;7824.100228473727;7825.060992680887;2114.186038375225;2138.251611935818;2141.528863650002;2142.1324363632752;2146.892655367233;2132.637451633226;2164.6861205125324;2166.155199472207;2166.166026079955;2167.0464995402053;2045.7442819647545;2060.392184305626;2077.4494758888904;2083.6770300603357;2085.843561732876;2072.948176295597;2092.5187206942337;2095.266656670161;2095.7485101787806;2101.684747287904;1740.6866763982478;1805.341050516552;2038.9592208155902;2041.7447819022623;2102.8002339777695;7527.548347099191;7581.772546823604;7598.316151546387;7605.087389513238;7617.80955476112;4566.977165114186;4708.764561771898;4712.034033023222;4714.599259062949;4724.905501889977;2898.7101289871057;2940.7500362469;2974;2975.2710585906375;2978.8659510322086;1112.1825458288151;1120.9047230985377;1137.2096393006864;1153.0832987774738;1158.1834806461425;10327.948360258151;10341.741456463573;10354.637587684394;10404.531796069195;10406.843897341547;3887.747296425723;4111.1366613011005;4168.6039826216465;4172.916541669149;4194.853180138681;8033.678652853937;8151.266386025168;8154.551499667576;8157.95921020403;8160.591970401575;4526.411566496335;4574.376096485699;4590.517995610479;4599.264117741212;4619.69971951822;4020.618041286073;4047.494063242095;4119.6910231732145;4129.194807012604;4163.97918010408;3301.7688761786444;3305.3638448967436;3366.73066154707;3390.983045084759;3394.304167645627;979.0894468144694;984.7390441532999;990.9157721593601;997.4314640654902;1004.924630652704;782.964766585505;812.5287333346645;813.2355585749376;824.1387749801472;827.4662842466576;4803.78382972767;4948.802047918115;5060.924086138711;5087.109755792721;5171.198464238049;497.0828820825571;514.4418306137808;517.6971471689104;520.0664806672032;528.9788408463695;3053.6335639723375;3089.1813669377634;3109.9378012439624;3135.7177853992794;3169.8573564189205;8064.072631647318;8395.580220988926;8434.62044208012;8753.212210900921;9075.909240907638;9284.164425201765;9288.48913309761;9289.767755806159;9290.860637090449;9300.627974881065;6677.499187560952;6778.525503214826;6793.082933803972;6793.626350550767;6849.315068493111;5005.824796132118;5042.218456139304;5054.444011158843;5267.604929630216;5268.420473747961;8277.95861020691;8439.240468357877;8454.239118479367;8515.27620152294;8577.485350878967;8161.551114688512;8446.619902104543;8530.829383412422;8568.057513673366;8588.871166932377;7210.639468026473;7254.455915806441;7302.525335853153;7326.81682957909;7350.264973502607;7105.218425973242;7147.463940204401;7216.494845360774;7217.170025446931;7217.67520461591;6433.195850518685;6509.433962264151;6714.52998290115;6793.082933803873;6835.555688880207;6515.609063456208;6526.510511711545;6532.346765323429;6544.312847151171;6584.50616203777;5941.31674857397;6027.457528822427;6031.215941927521;6032.547558933185;6042.758289668364;4577.908441831211;4588.472325682589;4662.160811054043;4710.152172608826;4776.21192503233;4472.821087156477;4514.751688657156;4564.863054108382;4571.360009598592;4749.382580264543 19:45:54 INFO - PROCESS | 19513 | |4;prototype.html;354.9662782035706;371.3167771300806;372.6236501133855;374.3916136278547;375.11472923899595;4766;4925.852224433273;5052.797888084477;5092.6573776063005;5101.693898366096;4796.592289655384;4878.585320247775;4932.679375840567;4982.725950072744;4984.651074424792;4020.0150963014103;4029.516458025034;4037.2732908076537;4045.8988525286954;4049.7975101244965;3920.4707364505853;4056.5132184137947;4069.613386728271;4076.2255171517463;4081.897952551195;3981.1838573092446;3995.420664003718;3995.760254384746;4025.1748391579804;4030.9798451007705;3984.78464068459;3994.400839874013;4005.719599628027;4016.15660711251;4020.1356708307853;3663.7069034477445;3670.2475992421755;3683.189698266377;3693.4275187346007;3695.334839728848;4619.445666520006;4876.4642385959105;4899.706017638953;4910.533499317559;4947.861991741884;4407.801648925806;4416.741228749791;4426.62373698236;4437.822487100512;4443.7111587746695;5256.816011439581;5260.316158899357;5277.150338817034;5277.23480095384;5310.769476819706;3637.3629609273185;3680.7423480356388;3696.31618150643;3741.962580374216;3750.906227344311;5268.473152684701;5424.728763561806;5429.864253393657;5491.533219676332;5506.979904669573;3787.242551489713;3807.695384369244;3813.7521061130938;3827.9617203827606;3841;3832.2335532893535;3844.826982785781;3866.3040652682225;3871.728978971445;3877.4571559981623;4214.283571792741;4411.558844115562;4427.966842712752;4443.311286750559;4451.465824101128;5201.973990130025;5208.166693329125;5214.947850521522;5225.947740522623;5253.290805741252;1253.1916913590683;1264.5890085722176;1279.688319472548;1279.7376537809728;1283.6277479531018;832.8958880139983;877.8595424732013;889.6886089868494;902.2962089570145;906.8458362078462;4397.692161548661;4426.0566723129405;4440.33394990754;4443.911121777546;4460.375547423363;5209.426963034063;5211.348581427322;5211.739413029333;5226.947730522722;5236.6334356595435;4236.76697781625;4427.933580996288;4482.27432438507;4489.73061616304;4498.5501449854755;2751.995521634629;2757.5587905934954;2767.6817166026167;2768.820026698258;2772.9445411091665;4876.780544875489;4877.95122048775;4883.389576302962;4893.6329775266995;4893.902121957541;5028.75351639321;5285.339332583427;5312.681239125664;5321.547668448148;5348.352565480112;4759.214729569651;4818;4879.39007624047;4882.365292512021;4885.633577481632;5094.566961808139;5204.193350030751;5242.344706911636;5244.737763111752;5279;4901.3383193268455;5085.122022209112;5096.796128154832;5101.311322971352;5115.386153661584;4719.4100737407825;4814.1815891297865;4823.469418364048;4836.782344794422;4953.702777833341;3772.320982223226;3775.528058992626;3793.430985352219;3800.795538587557;3803.3534299168655;5369.48989845964;5377;5397.8380648580605;5402.675839449488;5408.918866216931;4945.184044632595;5180.9740951295;5186.58507319406;5198.714070726146;5296.099663057366;5174.741262936763;5186.377634683862;5202.3497062867145;5203.531682148625;5217.843464696065;5106.872328191676;5108.157154069537;5125.821525851573;5129.820456284161;5156.5616922562;5040.470750571138;5130.769115389742;5169.793208271777;5172.5603323716405;5176.6704982758665;5069.9746501267255;5078.7303174206445;5094.872628184325;5097.235414687826;5121.897562048813 19:45:54 INFO - PROCESS | 19513 | |5;yui.html;643.8519140597665;645.0839807473387;713.21546299071;739.1647438394615;742.2058397514658;11855.585054523093;12779.744405111904;12781.936090319547;12792.93603531981;12814;12578.874211257884;12688.746225075492;12783.680407989781;12793.424295906683;12846.972242220623;780.0561320801837;787.370103916867;801.5911884938694;802.430274505102;802.5706247157772;799.1968072087544;799.5802203843;800.671724592917;800.8958835351397;801.7394346837272;837.1962915601031;840.1514470384894;840.9075001749833;841.47828346425;844.4468872888268;833.5415521463171;839.529863276564;839.5969934431477;840.7855996720858;841.146236569882;609.5519792952176;609.8780243951184;612.3937302070963;613.4417679911304;616.9167162433059;12605.495780168781;13080.280584567845;13170.53903113396;13182.53861114866;13200.20798752078;12473.875261247362;12881.227126372447;12883.613491595268;12981.805272920914;13385.46985131128;9569.904300957041;9741.659041933499;9752.512374381253;9760.219182465456;10277.588896444207;3167.9683203167906;3171.444997125494;3195.12134163107;3200;3338.582677165354;58026.41973580295;58795.70602146962;59268.40731592672;59422.40577594212;61199.69400153015;8880.51157186348;9275.953620231856;9355.158035776676;9398.859017114748;9557.23542116629;18676.81323186751;18731.719024214646;18906.81093189078;19010;19011.714824277646;6449.709763060673;6514.837129071811;6597.703103360264;6609.504287178481;6706.597604143767;3345.6821601947777;3440.1571614953996;3483.7213022958103;3496.230829217568;3500.387432199406;2928.472874882499;2976.4939960206816;2983.925401864971;3001.8048826826184;3001.9849900750355;503.9571636410873;507.8146476536038;514.459817191947;520.3885434614389;526.2632314759368;476.9632678910714;496.2184559319087;513.8047541934042;575.5481946671842;579.4118969246218;7342.889856652155;7889.447738658354;7967.3626109911065;8073.959630201929;8476.533790641573;506.11935232695583;507.1505228741843;519.4649511003672;524.4964833759633;541.7074779618962;3794.905127371838;3812.904677383088;4040.616141466556;4447.2439685253585;4774.498677638868;61343.38656613466;61773.382266177665;63026;64411.35588644147;64796.67601662056;55043;55429.445705543236;55457.168142477894;55712.72143639337;58432;50202.49797502051;50444.49555504398;50899.74550127226;50999;51373.48626513762;8923.286137108886;9208.677696280598;9235.722928312161;9343.859842102238;9345.345825792401;33874.49188262227;34451.65548344485;35459.29081418409;35498.64501354953;35820.820895895355;39534.80232598934;40151;40599.39100913548;41200.1759964793;43534.78232608816;10447.634332798316;10761;10845.546899594729;10932.617358392417;10962.725931851768;11759.570214644567;12050.965396499714;12420.565280215149;12448;12471.314077725812;11419.371934543347;11499.367534785333;11537.596184133516;11745.060395168537;12104.09219308573;11350.489227984926;11369.147313951653;11523.481443335239;11987.64037078889;12039.578614748441;11721.179517433698;11820.22270561945;11839.704007399883;11891.82162267584;12127.575534856238;8810.251128654136;9045.954770226106;9048.502332371783;9056.501892395981;9641.903580964101;9734.610615575297;9824.705258842247;9873.50632468388;9904.554295056596;9909.45497997617 19:45:54 INFO - PROCESS | 19513 | __end_tp_report 19:45:54 INFO - PROCESS | 19513 | __start_cc_report 19:45:54 INFO - PROCESS | 19513 | _x_x_mozilla_cycle_collect,396 19:45:54 INFO - PROCESS | 19513 | __end_cc_report 19:45:54 INFO - PROCESS | 19513 | __startTimestamp1487821554255__endTimestamp 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | ------- Summary: start ------- 19:45:54 INFO - PROCESS | 19513 | Number of tests: 6 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#0] dojo.html Cycles:165 Average:4515.73 Median:4630.33 stddev:467.95 (10.1%) stddev-sans-first:441.81 19:45:54 INFO - PROCESS | 19513 | Values: 2498.3 2545.8 2555.9 2571.1 2583.8 4435.9 4565.0 4582.8 4601.3 4669.9 4453.0 4553.8 4570.6 4580.2 4649.6 4202.3 4254.0 4282.9 4283.7 4350.7 4279.3 4308.7 4339.7 4347.9 4368.5 4099.3 4127.8 4130.3 4224.0 4285.5 4156.1 4185.1 4196.7 4225.3 4237.9 4147.9 4161.3 4176.6 4196.7 4203.5 4447.9 4473.2 4484.8 4511.0 4535.6 4803.8 4825.2 4842.1 4854.4 4900.6 4860.8 4873.7 4877.8 4889.0 4928.2 4495.7 4569.5 4578.6 4582.6 4608.7 4925.1 4984.9 4985.4 4987.9 4996.9 4763.2 4792.4 4795.3 4797.7 4851.7 4880.1 4880.4 4894.0 4895.0 4911.5 4823.7 4857.2 4871.7 4880.9 4913.6 4654.6 4668.5 4680.1 4724.6 4732.3 4051.5 4062.5 4063.3 4063.8 4070.0 3582.4 3611.8 3632.2 3641.6 3690.5 4237.0 4248.7 4251.8 4275.4 4285.9 4720.5 4881.6 4907.4 4907.7 4929.6 4138.9 4243.9 4281.1 4291.6 4325.5 4441.5 4443.2 4448.1 4454.3 4456.3 4599.2 4607.0 4624.9 4624.9 4665.2 4780.5 4817.0 4852.1 4884.4 4891.4 4576.9 4579.7 4581.9 4633.7 4633.8 4918.3 4926.4 4930.2 4939.8 4944.1 4756.5 4798.6 4826.1 5009.4 5061.4 4697.1 4726.8 4731.5 4773.6 4789.8 4718.6 4724.1 4729.9 4734.5 4781.8 4561.5 4588.6 4630.3 4646.1 4721.6 4815.5 4862.4 4868.6 4889.9 4893.3 4738.7 4744.5 4756.3 4770.0 4817.8 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#1] ext.html Cycles:180 Average:42162.47 Median:3383.53 stddev:73075.71 (2159.7%) stddev-sans-first:73213.86 19:45:54 INFO - PROCESS | 19513 | Values: 531.4 533.2 552.8 559.8 622.0 34212.3 38606.6 38810.8 38906.8 38934.4 39123.8 39651.0 39664.8 39664.8 39682.4 7606.4 7772.1 7795.8 9205.6 9520.6 9301.4 9358.5 9416.6 9447.4 9475.4 9432.6 9459.6 9460.8 9487.7 9577.3 9495.9 9498.6 9541.9 9552.0 9557.4 9098.5 9099.4 9108.1 9130.6 9144.3 32922.5 33182.2 33363.7 33426.7 33458.0 3251.9 3333.0 3341.7 3360.2 3387.7 24780.0 24892.8 25040.0 25109.6 25929.9 3351.8 3382.9 3384.2 3391.3 3415.5 229579.0 231785.0 232191.0 232281.0 232319.0 38228.2 39149.2 41506.2 45855.5 46335.0 49424.5 49745.8 49803.3 49980.8 50006.0 12323.5 12495.3 12508.6 12550.9 12602.6 9443.0 9490.5 9570.4 9573.0 9612.5 15455.5 15523.5 15635.4 15653.5 15681.6 6437.7 6645.1 6654.7 6657.4 6665.2 750.5 765.2 770.9 791.6 796.6 26235.0 27038.3 27362.6 27381.9 27449.5 810.6 886.1 916.6 925.5 933.8 14310.4 14332.6 14487.2 14491.2 14558.3 327745.0 328947.0 329562.0 330534.0 330707.0 48154.5 48420.5 48671.5 48749.0 48768.5 1139.1 1173.5 1187.0 1190.7 1191.3 24944.6 25106.4 25808.4 26068.0 26128.6 40207.0 40311.2 40382.6 40440.0 40504.6 265787.0 266111.0 266288.0 267576.0 267861.0 21045.8 21594.2 23902.4 24025.9 24510.0 35431.0 35489.8 35556.0 40022.8 40545.4 30564.0 30994.0 31362.7 31850.0 32829.3 30595.7 30725.2 31072.7 31131.8 31871.7 30498.2 30972.4 31308.2 31392.2 31828.8 27344.6 28930.1 29070.1 29120.4 29248.4 22493.6 22638.9 22919.7 22939.9 23082.5 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#2] jquery.html Cycles:180 Average:26767.87 Median:461572.50 stddev:75012.33 (16.3%) stddev-sans-first:75196.59 19:45:54 INFO - PROCESS | 19513 | Values: 380.5 386.4 398.8 403.2 424.4 17341.9 17950.1 17972.1 17996.4 18544.7 17613.7 17674.6 17878.0 18208.8 18221.8 5056.4 5252.4 5262.2 5274.4 5385.7 5507.1 5636.7 5655.0 5667.3 5674.9 5454.5 5540.7 5651.7 5657.2 5659.7 5596.9 5673.9 5686.7 5688.4 5690.4 4867.2 5093.6 5141.3 5538.8 5562.2 12520.4 12551.9 12690.2 12711.2 20369.5 2045.2 2135.5 2172.9 2184.9 2215.5 12882.7 13203.7 13251.0 13717.0 14253.3 1583.7 1632.9 1653.6 1663.9 1664.1 72902.6 78111.0 79673.6 80085.0 82234.6 10612.6 10780.7 10964.8 11024.8 11051.8 15346.8 15379.3 15394.8 15415.7 15420.9 6551.6 6785.6 6835.2 6868.7 6904.9 5933.5 6028.1 6173.0 6191.7 6243.6 4622.9 4629.7 4747.0 4751.7 4870.3 1912.6 1993.6 2004.9 2006.4 2040.7 774.3 802.8 811.4 811.5 829.6 16778.7 16809.0 17281.7 17643.4 17760.5 800.8 806.6 816.3 825.4 839.0 9468.4 9751.8 9827.4 9885.4 9907.6 439807.0 446582.0 460435.0 461257.0 461888.0 86773.6 89426.0 89501.1 89781.6 90242.5 913.6 917.2 943.2 944.0 944.1 14996.9 15010.8 15677.6 15774.9 15775.3 36363.0 37598.0 37937.0 38312.6 38466.8 47752.5 49161.5 50536.0 50995.7 51898.0 6957.9 7512.1 7616.6 7779.5 7907.8 8786.4 11714.9 11822.2 12228.5 12301.9 6366.8 10288.6 10435.2 10742.1 10795.4 10127.9 10194.8 10436.5 10996.6 11047.2 10291.9 10292.9 10434.9 10441.7 10932.9 7782.5 7822.0 7940.4 7957.2 8028.0 9728.3 10372.2 10672.0 10708.0 11039.9 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#3] mootools.html Cycles:180 Average:4948.17 Median:5048.33 stddev:2752.06 (54.5%) stddev-sans-first:2746.57 19:45:54 INFO - PROCESS | 19513 | Values: 1360.4 1363.4 1365.3 1368.9 1382.6 7258.9 7525.7 7656.2 7661.5 7670.3 7686.1 7809.2 7811.3 7824.1 7825.1 2114.2 2138.3 2141.5 2142.1 2146.9 2132.6 2164.7 2166.2 2166.2 2167.0 2045.7 2060.4 2077.4 2083.7 2085.8 2072.9 2092.5 2095.3 2095.7 2101.7 1740.7 1805.3 2039.0 2041.7 2102.8 7527.5 7581.8 7598.3 7605.1 7617.8 4567.0 4708.8 4712.0 4714.6 4724.9 2898.7 2940.8 2974.0 2975.3 2978.9 1112.2 1120.9 1137.2 1153.1 1158.2 10327.9 10341.7 10354.6 10404.5 10406.8 3887.7 4111.1 4168.6 4172.9 4194.9 8033.7 8151.3 8154.6 8158.0 8160.6 4526.4 4574.4 4590.5 4599.3 4619.7 4020.6 4047.5 4119.7 4129.2 4164.0 3301.8 3305.4 3366.7 3391.0 3394.3 979.1 984.7 990.9 997.4 1004.9 783.0 812.5 813.2 824.1 827.5 4803.8 4948.8 5060.9 5087.1 5171.2 497.1 514.4 517.7 520.1 529.0 3053.6 3089.2 3109.9 3135.7 3169.9 8064.1 8395.6 8434.6 8753.2 9075.9 9284.2 9288.5 9289.8 9290.9 9300.6 6677.5 6778.5 6793.1 6793.6 6849.3 5005.8 5042.2 5054.4 5267.6 5268.4 8278.0 8439.2 8454.2 8515.3 8577.5 8161.6 8446.6 8530.8 8568.1 8588.9 7210.6 7254.5 7302.5 7326.8 7350.3 7105.2 7147.5 7216.5 7217.2 7217.7 6433.2 6509.4 6714.5 6793.1 6835.6 6515.6 6526.5 6532.3 6544.3 6584.5 5941.3 6027.5 6031.2 6032.5 6042.8 4577.9 4588.5 4662.2 4710.2 4776.2 4472.8 4514.8 4564.9 4571.4 4749.4 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#4] prototype.html Cycles:180 Average:4305.27 Median:4877.37 stddev:1224.09 (25.1%) stddev-sans-first:1191.07 19:45:54 INFO - PROCESS | 19513 | Values: 355.0 371.3 372.6 374.4 375.1 4766.0 4925.9 5052.8 5092.7 5101.7 4796.6 4878.6 4932.7 4982.7 4984.7 4020.0 4029.5 4037.3 4045.9 4049.8 3920.5 4056.5 4069.6 4076.2 4081.9 3981.2 3995.4 3995.8 4025.2 4031.0 3984.8 3994.4 4005.7 4016.2 4020.1 3663.7 3670.2 3683.2 3693.4 3695.3 4619.4 4876.5 4899.7 4910.5 4947.9 4407.8 4416.7 4426.6 4437.8 4443.7 5256.8 5260.3 5277.2 5277.2 5310.8 3637.4 3680.7 3696.3 3742.0 3750.9 5268.5 5424.7 5429.9 5491.5 5507.0 3787.2 3807.7 3813.8 3828.0 3841.0 3832.2 3844.8 3866.3 3871.7 3877.5 4214.3 4411.6 4428.0 4443.3 4451.5 5202.0 5208.2 5214.9 5225.9 5253.3 1253.2 1264.6 1279.7 1279.7 1283.6 832.9 877.9 889.7 902.3 906.8 4397.7 4426.1 4440.3 4443.9 4460.4 5209.4 5211.3 5211.7 5226.9 5236.6 4236.8 4427.9 4482.3 4489.7 4498.6 2752.0 2757.6 2767.7 2768.8 2772.9 4876.8 4878.0 4883.4 4893.6 4893.9 5028.8 5285.3 5312.7 5321.5 5348.4 4759.2 4818.0 4879.4 4882.4 4885.6 5094.6 5204.2 5242.3 5244.7 5279.0 4901.3 5085.1 5096.8 5101.3 5115.4 4719.4 4814.2 4823.5 4836.8 4953.7 3772.3 3775.5 3793.4 3800.8 3803.4 5369.5 5377.0 5397.8 5402.7 5408.9 4945.2 5181.0 5186.6 5198.7 5296.1 5174.7 5186.4 5202.3 5203.5 5217.8 5106.9 5108.2 5125.8 5129.8 5156.6 5040.5 5130.8 5169.8 5172.6 5176.7 5070.0 5078.7 5094.9 5097.2 5121.9 19:45:54 INFO - PROCESS | 19513 | 19:45:54 INFO - PROCESS | 19513 | [#5] yui.html Cycles:180 Average:14317.21 Median:25756.40 stddev:17571.20 (68.2%) stddev-sans-first:17590.49 19:45:54 INFO - PROCESS | 19513 | Values: 643.9 645.1 713.2 739.2 742.2 11855.6 12779.7 12781.9 12792.9 12814.0 12578.9 12688.7 12783.7 12793.4 12847.0 780.1 787.4 801.6 802.4 802.6 799.2 799.6 800.7 800.9 801.7 837.2 840.2 840.9 841.5 844.4 833.5 839.5 839.6 840.8 841.1 609.6 609.9 612.4 613.4 616.9 12605.5 13080.3 13170.5 13182.5 13200.2 12473.9 12881.2 12883.6 12981.8 13385.5 9569.9 9741.7 9752.5 9760.2 10277.6 3168.0 3171.4 3195.1 3200.0 3338.6 58026.4 58795.7 59268.4 59422.4 61199.7 8880.5 9276.0 9355.2 9398.9 9557.2 18676.8 18731.7 18906.8 19010.0 19011.7 6449.7 6514.8 6597.7 6609.5 6706.6 3345.7 3440.2 3483.7 3496.2 3500.4 2928.5 2976.5 2983.9 3001.8 3002.0 504.0 507.8 514.5 520.4 526.3 477.0 496.2 513.8 575.5 579.4 7342.9 7889.4 7967.4 8074.0 8476.5 506.1 507.2 519.5 524.5 541.7 3794.9 3812.9 4040.6 4447.2 4774.5 61343.4 61773.4 63026.0 64411.4 64796.7 55043.0 55429.4 55457.2 55712.7 58432.0 50202.5 50444.5 50899.7 50999.0 51373.5 8923.3 9208.7 9235.7 9343.9 9345.3 33874.5 34451.7 35459.3 35498.6 35820.8 39534.8 40151.0 40599.4 41200.2 43534.8 10447.6 10761.0 10845.5 10932.6 10962.7 11759.6 12051.0 12420.6 12448.0 12471.3 11419.4 11499.4 11537.6 11745.1 12104.1 11350.5 11369.1 11523.5 11987.6 12039.6 11721.2 11820.2 11839.7 11891.8 12127.6 8810.3 9046.0 9048.5 9056.5 9641.9 9734.6 9824.7 9873.5 9904.6 9909.5 19:45:54 INFO - PROCESS | 19513 | -------- Summary: end -------- 19:45:54 INFO - PROCESS | 19513 | 19:45:55 INFO - TEST-INFO | 19513: exit 0 19:45:55 INFO - TEST-OK | dromaeo_css | took 1119506ms 19:45:55 INFO - TEST-START | kraken 19:45:55 INFO - operating with platform_type : linux_ 19:45:55 INFO - Initialising browser for kraken test... 19:45:55 INFO - TEST-INFO | started process 19885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp5HUvI/profile http://localhost:46815/getInfo.html) 19:45:58 INFO - PROCESS | 19885 | Iterating over profile /tmp/tmpp5HUvI/profile 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/places.sqlite-wal = Wed Feb 22 2017 19:45:59 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/content-prefs.sqlite = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/compatibility.ini = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/extensions = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/directoryLinks.json = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/bookmarkbackups = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/cache2 = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/localstore.rdf = Wed Feb 22 2017 19:26:35 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/places.sqlite = Wed Feb 22 2017 19:45:59 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | __metrics Screen width/height:1600/1200 19:45:59 INFO - PROCESS | 19885 | colorDepth:24 19:45:59 INFO - PROCESS | 19885 | Browser inner width/height: 1024/697 19:45:59 INFO - PROCESS | 19885 | __metrics 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/extensions.json = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/addons.json = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/safebrowsing = Wed Feb 22 2017 19:45:59 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/permissions.sqlite = Wed Feb 22 2017 19:45:56 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/key3.db = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/startupCache = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/pluginreg.dat = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | Stat failure 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/cert8.db = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/places.sqlite-shm = Wed Feb 22 2017 19:45:56 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/prefs.js = Wed Feb 22 2017 19:26:35 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/minidumps = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/.parentlock = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/extensions.ini = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/thumbnails = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/gmp = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/secmod.db = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:45:59 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/frequencyCap.json = Wed Feb 22 2017 19:45:57 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/crashes = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/user.js = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:45:59 INFO - PROCESS | 19885 | No birth date. Using mtime. 19:45:59 INFO - PROCESS | 19885 | Using date: /tmp/tmpp5HUvI/profile/blocklist.xml = Wed Feb 22 2017 19:45:55 GMT-0800 (PST) 19:46:00 ERROR - PROCESS | 19885 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:46:01 INFO - TEST-INFO | 19885: exit 0 19:46:01 INFO - Browser initialized. 19:46:01 INFO - Running cycle 1/1 for kraken test... 19:46:01 INFO - TEST-INFO | started process 20013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp5HUvI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:13 INFO - PROCESS | 20013 | RSS: Main: 153497600 19:46:13 INFO - PROCESS | 20013 | 19:46:16 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46815/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:17 INFO - PROCESS | 20013 | RSS: Main: 159629312 19:46:17 INFO - PROCESS | 20013 | 19:46:20 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46815/tests/kraken/driver.html?testName=audio-dft) 19:46:20 INFO - PROCESS | 20013 | RSS: Main: 158687232 19:46:20 INFO - PROCESS | 20013 | 19:46:23 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46815/tests/kraken/driver.html?testName=audio-fft) 19:46:24 INFO - PROCESS | 20013 | RSS: Main: 155779072 19:46:24 INFO - PROCESS | 20013 | 19:46:26 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46815/tests/kraken/driver.html?testName=audio-oscillator) 19:46:27 INFO - PROCESS | 20013 | RSS: Main: 156086272 19:46:27 INFO - PROCESS | 20013 | 19:46:29 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46815/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:30 INFO - PROCESS | 20013 | RSS: Main: 156676096 19:46:30 INFO - PROCESS | 20013 | 19:46:33 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46815/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:33 INFO - PROCESS | 20013 | RSS: Main: 162201600 19:46:33 INFO - PROCESS | 20013 | 19:46:37 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46815/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:38 INFO - PROCESS | 20013 | RSS: Main: 158420992 19:46:38 INFO - PROCESS | 20013 | 19:46:40 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46815/tests/kraken/driver.html?testName=json-parse-financial) 19:46:41 INFO - PROCESS | 20013 | RSS: Main: 159506432 19:46:41 INFO - PROCESS | 20013 | 19:46:43 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46815/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:43 INFO - PROCESS | 20013 | RSS: Main: 159858688 19:46:43 INFO - PROCESS | 20013 | 19:46:45 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:46 INFO - PROCESS | 20013 | RSS: Main: 159518720 19:46:46 INFO - PROCESS | 20013 | 19:46:48 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:49 INFO - PROCESS | 20013 | RSS: Main: 159924224 19:46:49 INFO - PROCESS | 20013 | 19:46:52 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:46:53 INFO - PROCESS | 20013 | RSS: Main: 160088064 19:46:53 INFO - PROCESS | 20013 | 19:46:56 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:46:56 INFO - PROCESS | 20013 | RSS: Main: 163098624 19:46:56 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | Cycle 1(1): loaded http://localhost:46815/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46815/tests/kraken/driver.html?testName=ai-astar) 19:46:58 INFO - PROCESS | 20013 | RSS: Main: 163848192 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | __start_tp_report 19:46:58 INFO - PROCESS | 20013 | _x_x_mozilla_page_load 19:46:58 INFO - PROCESS | 20013 | _x_x_mozilla_page_load_details 19:46:58 INFO - PROCESS | 20013 | |i|pagename|runs| 19:46:58 INFO - PROCESS | 20013 | |0;ai-astar;108;116;115;117;115;114;115;116;118;115 19:46:58 INFO - PROCESS | 20013 | |1;audio-beat-detection;153;126;134;185;128;129;129;129;158;130 19:46:58 INFO - PROCESS | 20013 | |2;audio-dft;164;166;170;171;167;161;164;165;165;164 19:46:58 INFO - PROCESS | 20013 | |3;audio-fft;77;80;77;107;78;79;78;78;93;78 19:46:58 INFO - PROCESS | 20013 | |4;audio-oscillator;88;85;105;85;85;84;105;85;86;106 19:46:58 INFO - PROCESS | 20013 | |5;imaging-gaussian-blur;114;114;114;114;114;117;114;113;114;113 19:46:58 INFO - PROCESS | 20013 | |6;imaging-darkroom;150;150;152;151;154;151;151;151;151;150 19:46:58 INFO - PROCESS | 20013 | |7;imaging-desaturate;96;96;95;96;97;96;96;96;97;97 19:46:58 INFO - PROCESS | 20013 | |8;json-parse-financial;66;65;66;65;65;68;67;66;67;67 19:46:58 INFO - PROCESS | 20013 | |9;json-stringify-tinderbox;66;67;66;67;66;66;65;66;65;67 19:46:58 INFO - PROCESS | 20013 | |10;stanford-crypto-aes;93;86;87;88;88;90;87;87;99;87 19:46:58 INFO - PROCESS | 20013 | |11;stanford-crypto-ccm;139;139;150;147;136;150;139;141;185;141 19:46:58 INFO - PROCESS | 20013 | |12;stanford-crypto-pbkdf2;187;188;188;180;182;188;192;183;183;182 19:46:58 INFO - PROCESS | 20013 | |13;stanford-crypto-sha256-iterative;73;70;71;70;76;75;72;71;71;74 19:46:58 INFO - PROCESS | 20013 | __end_tp_report 19:46:58 INFO - PROCESS | 20013 | __start_cc_report 19:46:58 INFO - PROCESS | 20013 | _x_x_mozilla_cycle_collect,900 19:46:58 INFO - PROCESS | 20013 | __end_cc_report 19:46:58 INFO - PROCESS | 20013 | __startTimestamp1487821618835__endTimestamp 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | ------- Summary: start ------- 19:46:58 INFO - PROCESS | 20013 | Number of tests: 14 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.50 stddev:2.69 (2.3%) stddev-sans-first:1.22 19:46:58 INFO - PROCESS | 20013 | Values: 108.0 116.0 115.0 117.0 115.0 114.0 115.0 116.0 118.0 115.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:132.00 stddev:19.31 (14.6%) stddev-sans-first:19.91 19:46:58 INFO - PROCESS | 20013 | Values: 153.0 126.0 134.0 185.0 128.0 129.0 129.0 129.0 158.0 130.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#2] audio-dft Cycles:10 Average:165.70 Median:165.50 stddev:2.98 (1.8%) stddev-sans-first:3.10 19:46:58 INFO - PROCESS | 20013 | Values: 164.0 166.0 170.0 171.0 167.0 161.0 164.0 165.0 165.0 164.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#3] audio-fft Cycles:10 Average:82.50 Median:78.00 stddev:9.83 (12.6%) stddev-sans-first:10.23 19:46:58 INFO - PROCESS | 20013 | Values: 77.0 80.0 77.0 107.0 78.0 79.0 78.0 78.0 93.0 78.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:85.00 stddev:9.67 (11.4%) stddev-sans-first:10.18 19:46:58 INFO - PROCESS | 20013 | Values: 88.0 85.0 105.0 85.0 85.0 84.0 105.0 85.0 86.0 106.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.10 (1.0%) stddev-sans-first:1.17 19:46:58 INFO - PROCESS | 20013 | Values: 114.0 114.0 114.0 114.0 114.0 117.0 114.0 113.0 114.0 113.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:1.20 (0.8%) stddev-sans-first:1.20 19:46:58 INFO - PROCESS | 20013 | Values: 150.0 150.0 152.0 151.0 154.0 151.0 151.0 151.0 151.0 150.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 19:46:58 INFO - PROCESS | 20013 | Values: 96.0 96.0 95.0 96.0 97.0 96.0 96.0 96.0 97.0 97.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 19:46:58 INFO - PROCESS | 20013 | Values: 66.0 65.0 66.0 65.0 65.0 68.0 67.0 66.0 67.0 67.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 19:46:58 INFO - PROCESS | 20013 | Values: 66.0 67.0 66.0 67.0 66.0 66.0 65.0 66.0 65.0 67.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:88.00 stddev:3.99 (4.5%) stddev-sans-first:3.99 19:46:58 INFO - PROCESS | 20013 | Values: 93.0 86.0 87.0 88.0 88.0 90.0 87.0 87.0 99.0 87.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:144.00 stddev:14.32 (9.9%) stddev-sans-first:14.92 19:46:58 INFO - PROCESS | 20013 | Values: 139.0 139.0 150.0 147.0 136.0 150.0 139.0 141.0 185.0 141.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:187.50 stddev:3.80 (2.0%) stddev-sans-first:3.98 19:46:58 INFO - PROCESS | 20013 | Values: 187.0 188.0 188.0 180.0 182.0 188.0 192.0 183.0 183.0 182.0 19:46:58 INFO - PROCESS | 20013 | 19:46:58 INFO - PROCESS | 20013 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:72.50 stddev:2.11 (2.9%) stddev-sans-first:2.22 19:46:58 INFO - PROCESS | 20013 | Values: 73.0 70.0 71.0 70.0 76.0 75.0 72.0 71.0 71.0 74.0 19:46:58 INFO - PROCESS | 20013 | -------- Summary: end -------- 19:46:58 INFO - PROCESS | 20013 | 19:47:00 INFO - TEST-INFO | 20013: exit 0 19:47:00 INFO - TEST-OK | kraken | took 64918ms 19:47:00 INFO - SUITE-END | took 1184s 19:47:00 INFO - Completed test suite (00:19:44) 19:47:00 INFO - javascript benchmark 19:47:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6251.483302683241, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2498.3129639349177, 2545.7581529754666, 2555.8977640894364, 2571.0615625296773, 2583.806214533909, 4435.911281774363, 4565.018521017981, 4582.839600613975, 4601.309803529468, 4669.9066018679605, 4453.0, 4553.795079221435, 4570.56579624936, 4580.175568397687, 4649.58153766161, 4202.3486359614235, 4253.978730106345, 4282.850100246492, 4283.67872409569, 4350.738955662655, 4279.293916503789, 4308.741475511463, 4339.696221264513, 4347.913041739163, 4368.475782906055, 4099.344104943225, 4127.772972486512, 4130.318497447917, 4223.957760422418, 4285.507166675841, 4156.085661154541, 4185.121124563845, 4196.664266858644, 4225.260579398593, 4237.894052648678, 4147.9170416591805, 4161.25097482453, 4176.561461046607, 4196.685248606336, 4203.516595591485, 4447.888802779924, 4473.194824931511, 4484.775761211927, 4510.954890451087, 4535.614472769818, 4803.831865884677, 4825.179719447702, 4842.12841688496, 4854.417469903598, 4900.558949694509, 4860.756962151878, 4873.731944743037, 4877.780499877514, 4889.0, 4928.186849169882, 4495.662825288116, 4569.520200378945, 4578.587927086578, 4582.564656357641, 4608.700434471749, 4925.088858561177, 4984.925226121611, 4985.351904252424, 4987.925181122286, 4996.875078123004, 4763.2140696784645, 4792.448868380181, 4795.328653988445, 4797.712137271775, 4851.684640498356, 4880.072786170616, 4880.365552478155, 4894.0, 4894.95105048945, 4911.45973942866, 4823.662343635982, 4857.1742803723455, 4871.658983871095, 4880.902381952412, 4913.606911447076, 4654.581087702123, 4668.486466488684, 4680.063987202573, 4724.551167639069, 4732.290156476487, 4051.4530538376944, 4062.471878655756, 4063.2889244382113, 4063.817128229237, 4070.043539768152, 3582.373084710165, 3611.7832930024283, 3632.182758879284, 3641.617630148849, 3690.538682664667, 4237.025484138665, 4248.681348898845, 4251.787410629456, 4275.38006988991, 4285.871423857289, 4720.527947205321, 4881.585065269421, 4907.435644900811, 4907.656464047483, 4929.605631549539, 4138.937915931264, 4243.936340954888, 4281.079567892918, 4291.592298731615, 4325.480942286946, 4441.467023957146, 4443.200223959654, 4448.110377924428, 4454.28731402974, 4456.286994080931, 4599.218132917479, 4607.0, 4624.884377890579, 4624.930626040545, 4665.206914824488, 4780.474147843804, 4817.0, 4852.126617208936, 4884.389451318585, 4891.413030436371, 4576.931346029879, 4579.656525760648, 4581.908361832811, 4633.652476064376, 4633.837815676435, 4918.262260660929, 4926.359573255454, 4930.235813448921, 4939.827106051271, 4944.060628480577, 4756.476787553297, 4798.568128868279, 4826.107170173529, 5009.373828271466, 5061.367329083864, 4697.084068606726, 4726.787294571684, 4731.526847315378, 4773.570378665798, 4789.7605119743175, 4718.646101542467, 4724.126036683225, 4729.881752956204, 4734.455537613081, 4781.808727650855, 4561.475430325422, 4588.587027167571, 4630.328602352707, 4646.117237724821, 4721.622270218306, 4815.542523460265, 4862.41651001882, 4868.61051115917, 4889.877753056202, 4893.314935908905, 4738.715677059249, 4744.478107408251, 4756.310335001474, 4769.976150119227, 4817.759112044314], "value": 4486.03390194075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.449949302472, 533.1789044870899, 552.8230966090847, 559.7537083683178, 621.9782307619234, 34212.31575368494, 38606.61393386065, 38810.80594597023, 38906.80546597267, 38934.415983760264, 39123.80438097812, 39651.0, 39664.80167599165, 39664.80167599165, 39682.40476392849, 7606.353459955911, 7772.145064042951, 7795.844083118334, 9205.585748641293, 9520.571574279174, 9301.395409298408, 9358.532073396336, 9416.62333506659, 9447.385919915218, 9475.431474111541, 9432.575534100983, 9459.574319155654, 9460.81078378432, 9487.667931622394, 9577.329586928918, 9495.90504094957, 9498.572564234626, 9541.904580954171, 9551.952240238823, 9557.426554406688, 9098.545072746336, 9099.408538444979, 9108.134727200906, 9130.589123489393, 9144.314176436726, 32922.50616240758, 33182.17044573881, 33363.6663633363, 33426.665733342845, 33458.0, 3251.9358530386494, 3332.950005749916, 3341.7159541439005, 3360.1599600099976, 3387.6781705737917, 24780.0, 24892.751072489224, 25040.0, 25109.62335564968, 25929.870350648314, 3351.8324083795715, 3382.915427114317, 3384.1539615096226, 3391.287829555796, 3415.5389022481895, 229579.0, 231785.0, 232190.9999999983, 232281.0, 232319.0, 38228.235435291135, 39149.2170156601, 41506.1698766023, 45855.5414445858, 46335.0, 49424.50575494199, 49745.75127124341, 49803.25295120576, 49980.750096249285, 50006.0, 12323.50705971751, 12495.250284982749, 12508.562200322946, 12550.874491255154, 12602.55891043809, 9443.0, 9490.478023708762, 9570.425774453555, 9573.0, 9612.471314077793, 15455.459058932884, 15523.45667901618, 15635.374585016472, 15653.530394088195, 15681.588472817608, 6437.678116094176, 6645.136132302867, 6654.735600235939, 6657.367550082734, 6665.166854143232, 750.4784174998324, 765.1583258415809, 770.9036370453694, 791.5962858941866, 796.585775396802, 26235.0, 27038.324041898715, 27362.589561156597, 27381.86309068442, 27449.45101097967, 810.550144669714, 886.1404437695425, 916.5692124701379, 925.5002298758595, 933.8459154239474, 14310.427582896567, 14332.641683957776, 14487.20320382368, 14491.20298383599, 14558.344874480674, 327745.0, 328947.0, 329562.0, 330534.0, 330707.0, 48154.518454815705, 48420.51579484231, 48671.513284867404, 48749.0, 48768.512314876396, 1139.1342579639534, 1173.530587764901, 1186.988130118705, 1190.6844686158174, 1191.3090407563593, 24944.625830612917, 25106.35957786074, 25808.418548395068, 26068.0, 26128.60807087933, 40207.0, 40311.1937761249, 40382.596174037884, 40440.0, 40504.59495405008, 265787.0, 266111.0, 266288.0, 267576.0, 267861.0, 21045.789542104383, 21594.244201452875, 23902.402439939142, 24025.879870600536, 24510.0, 35431.0, 35489.82255088708, 35556.0, 40022.799886000386, 40545.391819122146, 30564.0, 30994.0, 31362.686373135977, 31850.04449866415, 32829.34341313112, 30595.694043059284, 30725.231869202555, 31072.68927310698, 31131.844340778152, 31871.68128318687, 30498.237544061576, 30972.380552388375, 31308.217294567818, 31392.215194620316, 31828.840855795574, 27344.589831152152, 28930.132096037152, 29070.12789616315, 29120.417591647623, 29248.41503169967, 22493.550128997, 22638.886805565868, 22919.65620515727, 22939.88530057339, 23082.53834923326], "value": 16548.822156504742, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5072431201592, 386.38178913738005, 398.7647288100021, 403.19763670296106, 424.3592175814521, 17341.913290433546, 17950.102494875253, 17972.101394930232, 17996.370127045557, 18544.72182917254, 17613.7357939631, 17674.64650706985, 17878.0, 18208.81791182088, 18221.817781822177, 5056.418511871127, 5252.424272718188, 5262.210668399733, 5274.4198138204765, 5385.703786291752, 5507.063799154133, 5636.7181640918, 5655.0386434306065, 5667.3482549506925, 5674.914876276859, 5454.53636440901, 5540.695261760601, 5651.660900345993, 5657.20799088128, 5659.688717120557, 5596.916046259309, 5673.914891276634, 5686.687232202248, 5688.402717714622, 5690.430956904297, 4867.2212446008825, 5093.567046801027, 5141.30592370029, 5538.806141785019, 5562.16567514872, 12520.436580353906, 12551.93724031374, 12690.238585684796, 12711.173773704772, 20369.49076273105, 2045.1819272291202, 2135.540858715384, 2172.945676358088, 2184.857984231036, 2215.4682876109778, 12882.742345153045, 13203.735925281537, 13251.0, 13717.0398072135, 14253.287335633177, 1583.6515966487354, 1632.9265183066673, 1653.5535405440585, 1663.9168041597993, 1664.0514906503297, 72902.63548682275, 78111.0, 79673.60163199205, 80085.0, 82234.58882705608, 10612.57549698019, 10780.676579702622, 10964.848690887502, 11024.834627480594, 11051.778964420666, 15346.76979845281, 15379.307931143125, 15394.769078463609, 15415.689666378483, 15420.92289538545, 6551.6069035858, 6785.55893866907, 6835.179778426621, 6868.737349758824, 6904.861902762017, 5933.495652869468, 6028.125921741411, 6172.950598398283, 6191.678120704736, 6243.625382477066, 4622.907541849211, 4629.67592268538, 4746.952530474651, 4751.667383283206, 4870.342503762017, 1912.5792325688326, 1993.601279744057, 2004.8897310647762, 2006.4181387397782, 2040.704097905825, 774.3418094619528, 802.7832485228955, 811.3874025111004, 811.5009269299456, 829.5769157729601, 16778.74831877523, 16809.0, 17281.654366912844, 17643.382481613084, 17760.467185984184, 800.7557694903111, 806.6370133439975, 816.269438852224, 825.4098319701442, 838.9916100839035, 9468.384555003902, 9751.756206094762, 9827.361221520578, 9885.357451765756, 9907.603695852085, 439807.0, 446582.0, 460435.0, 461257.0, 461888.0, 86773.56613217019, 89426.0, 89501.10498895058, 89781.55109224543, 90242.54878725564, 913.55235934393, 917.2111983694018, 943.1794338925042, 944.0370821761765, 944.1455482788087, 14996.925015374853, 15010.849891500946, 15677.608059798597, 15774.9211253943, 15775.29011194476, 36363.0, 37598.0, 37937.0, 38312.61687383202, 38466.80766596149, 47752.5224747762, 49161.508384915694, 50536.0, 50995.745021274655, 51898.0, 6957.930420695729, 7512.098548174254, 7616.619169041636, 7779.494332868346, 7907.762767116996, 8786.428882122642, 11714.88285117138, 11822.172447928562, 12228.510859565873, 12301.892829645374, 6366.840828979127, 10288.639897603847, 10435.165186784887, 10742.086922611354, 10795.40625265618, 10127.949360253151, 10194.847077293698, 10436.53035613384, 10996.615118470816, 11047.226694131334, 10291.897081029394, 10292.948535257276, 10434.895651043393, 10441.738956526147, 10932.890671093188, 7782.455228133977, 7821.96089019574, 7940.444168908121, 7957.244061814118, 8028.0, 9728.319017668695, 10372.17022638172, 10672.0, 10708.0, 11039.889601103887], "value": 8421.13657907019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.43541930099, 1363.3932899859567, 1365.255935515144, 1368.8836448901843, 1382.6128683968486, 7258.891116633248, 7525.698972041126, 7656.157822639505, 7661.502002369842, 7670.348020418264, 7686.116096648873, 7809.219078092188, 7811.257930496594, 7824.100228473727, 7825.060992680887, 2114.186038375225, 2138.251611935818, 2141.528863650002, 2142.1324363632752, 2146.892655367233, 2132.637451633226, 2164.6861205125324, 2166.155199472207, 2166.166026079955, 2167.0464995402053, 2045.7442819647545, 2060.392184305626, 2077.4494758888904, 2083.6770300603357, 2085.843561732876, 2072.948176295597, 2092.5187206942337, 2095.266656670161, 2095.7485101787806, 2101.684747287904, 1740.6866763982478, 1805.341050516552, 2038.9592208155902, 2041.7447819022623, 2102.8002339777695, 7527.548347099191, 7581.772546823604, 7598.316151546387, 7605.087389513238, 7617.80955476112, 4566.977165114186, 4708.764561771898, 4712.034033023222, 4714.599259062949, 4724.905501889977, 2898.7101289871057, 2940.7500362469, 2974.0, 2975.2710585906375, 2978.8659510322086, 1112.1825458288151, 1120.9047230985377, 1137.2096393006864, 1153.0832987774738, 1158.1834806461425, 10327.948360258151, 10341.741456463573, 10354.637587684394, 10404.531796069195, 10406.843897341547, 3887.747296425723, 4111.1366613011005, 4168.6039826216465, 4172.916541669149, 4194.853180138681, 8033.678652853937, 8151.266386025168, 8154.551499667576, 8157.95921020403, 8160.591970401575, 4526.411566496335, 4574.376096485699, 4590.517995610479, 4599.264117741212, 4619.69971951822, 4020.618041286073, 4047.494063242095, 4119.6910231732145, 4129.194807012604, 4163.97918010408, 3301.7688761786444, 3305.3638448967436, 3366.73066154707, 3390.983045084759, 3394.304167645627, 979.0894468144694, 984.7390441532999, 990.9157721593601, 997.4314640654902, 1004.924630652704, 782.964766585505, 812.5287333346645, 813.2355585749376, 824.1387749801472, 827.4662842466576, 4803.78382972767, 4948.802047918115, 5060.924086138711, 5087.109755792721, 5171.198464238049, 497.0828820825571, 514.4418306137808, 517.6971471689104, 520.0664806672032, 528.9788408463695, 3053.6335639723375, 3089.1813669377634, 3109.9378012439624, 3135.7177853992794, 3169.8573564189205, 8064.072631647318, 8395.580220988926, 8434.62044208012, 8753.212210900921, 9075.909240907638, 9284.164425201765, 9288.48913309761, 9289.767755806159, 9290.860637090449, 9300.627974881065, 6677.499187560952, 6778.525503214826, 6793.082933803972, 6793.626350550767, 6849.315068493111, 5005.824796132118, 5042.218456139304, 5054.444011158843, 5267.604929630216, 5268.420473747961, 8277.95861020691, 8439.240468357877, 8454.239118479367, 8515.27620152294, 8577.485350878967, 8161.551114688512, 8446.619902104543, 8530.829383412422, 8568.057513673366, 8588.871166932377, 7210.639468026473, 7254.455915806441, 7302.525335853153, 7326.81682957909, 7350.264973502607, 7105.218425973242, 7147.463940204401, 7216.494845360774, 7217.170025446931, 7217.67520461591, 6433.195850518685, 6509.433962264151, 6714.52998290115, 6793.082933803873, 6835.555688880207, 6515.609063456208, 6526.510511711545, 6532.346765323429, 6544.312847151171, 6584.50616203777, 5941.31674857397, 6027.457528822427, 6031.215941927521, 6032.547558933185, 6042.758289668364, 4577.908441831211, 4588.472325682589, 4662.160811054043, 4710.152172608826, 4776.21192503233, 4472.821087156477, 4514.751688657156, 4564.863054108382, 4571.360009598592, 4749.382580264543], "value": 3951.786429333676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.9662782035706, 371.3167771300806, 372.6236501133855, 374.3916136278547, 375.11472923899595, 4766.0, 4925.852224433273, 5052.797888084477, 5092.6573776063005, 5101.693898366096, 4796.592289655384, 4878.585320247775, 4932.679375840567, 4982.725950072744, 4984.651074424792, 4020.0150963014103, 4029.516458025034, 4037.2732908076537, 4045.8988525286954, 4049.7975101244965, 3920.4707364505853, 4056.5132184137947, 4069.613386728271, 4076.2255171517463, 4081.897952551195, 3981.1838573092446, 3995.420664003718, 3995.760254384746, 4025.1748391579804, 4030.9798451007705, 3984.78464068459, 3994.400839874013, 4005.719599628027, 4016.15660711251, 4020.1356708307853, 3663.7069034477445, 3670.2475992421755, 3683.189698266377, 3693.4275187346007, 3695.334839728848, 4619.445666520006, 4876.4642385959105, 4899.706017638953, 4910.533499317559, 4947.861991741884, 4407.801648925806, 4416.741228749791, 4426.62373698236, 4437.822487100512, 4443.7111587746695, 5256.816011439581, 5260.316158899357, 5277.150338817034, 5277.23480095384, 5310.769476819706, 3637.3629609273185, 3680.7423480356388, 3696.31618150643, 3741.962580374216, 3750.906227344311, 5268.473152684701, 5424.728763561806, 5429.864253393657, 5491.533219676332, 5506.979904669573, 3787.242551489713, 3807.695384369244, 3813.7521061130938, 3827.9617203827606, 3841.0, 3832.2335532893535, 3844.826982785781, 3866.3040652682225, 3871.728978971445, 3877.4571559981623, 4214.283571792741, 4411.558844115562, 4427.966842712752, 4443.311286750559, 4451.465824101128, 5201.973990130025, 5208.166693329125, 5214.947850521522, 5225.947740522623, 5253.290805741252, 1253.1916913590683, 1264.5890085722176, 1279.688319472548, 1279.7376537809728, 1283.6277479531018, 832.8958880139983, 877.8595424732013, 889.6886089868494, 902.2962089570145, 906.8458362078462, 4397.692161548661, 4426.0566723129405, 4440.33394990754, 4443.911121777546, 4460.375547423363, 5209.426963034063, 5211.348581427322, 5211.739413029333, 5226.947730522722, 5236.6334356595435, 4236.76697781625, 4427.933580996288, 4482.27432438507, 4489.73061616304, 4498.5501449854755, 2751.995521634629, 2757.5587905934954, 2767.6817166026167, 2768.820026698258, 2772.9445411091665, 4876.780544875489, 4877.95122048775, 4883.389576302962, 4893.6329775266995, 4893.902121957541, 5028.75351639321, 5285.339332583427, 5312.681239125664, 5321.547668448148, 5348.352565480112, 4759.214729569651, 4818.0, 4879.39007624047, 4882.365292512021, 4885.633577481632, 5094.566961808139, 5204.193350030751, 5242.344706911636, 5244.737763111752, 5279.0, 4901.3383193268455, 5085.122022209112, 5096.796128154832, 5101.311322971352, 5115.386153661584, 4719.4100737407825, 4814.1815891297865, 4823.469418364048, 4836.782344794422, 4953.702777833341, 3772.320982223226, 3775.528058992626, 3793.430985352219, 3800.795538587557, 3803.3534299168655, 5369.48989845964, 5377.0, 5397.8380648580605, 5402.675839449488, 5408.918866216931, 4945.184044632595, 5180.9740951295, 5186.58507319406, 5198.714070726146, 5296.099663057366, 5174.741262936763, 5186.377634683862, 5202.3497062867145, 5203.531682148625, 5217.843464696065, 5106.872328191676, 5108.157154069537, 5125.821525851573, 5129.820456284161, 5156.5616922562, 5040.470750571138, 5130.769115389742, 5169.793208271777, 5172.5603323716405, 5176.6704982758665, 5069.9746501267255, 5078.7303174206445, 5094.872628184325, 5097.235414687826, 5121.897562048813], "value": 3929.373438922053, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.8519140597665, 645.0839807473387, 713.21546299071, 739.1647438394615, 742.2058397514658, 11855.585054523093, 12779.744405111904, 12781.936090319547, 12792.93603531981, 12814.0, 12578.874211257884, 12688.746225075492, 12783.680407989781, 12793.424295906683, 12846.972242220623, 780.0561320801837, 787.370103916867, 801.5911884938694, 802.430274505102, 802.5706247157772, 799.1968072087544, 799.5802203843, 800.671724592917, 800.8958835351397, 801.7394346837272, 837.1962915601031, 840.1514470384894, 840.9075001749833, 841.47828346425, 844.4468872888268, 833.5415521463171, 839.529863276564, 839.5969934431477, 840.7855996720858, 841.146236569882, 609.5519792952176, 609.8780243951184, 612.3937302070963, 613.4417679911304, 616.9167162433059, 12605.495780168781, 13080.280584567845, 13170.53903113396, 13182.53861114866, 13200.20798752078, 12473.875261247362, 12881.227126372447, 12883.613491595268, 12981.805272920914, 13385.46985131128, 9569.904300957041, 9741.659041933499, 9752.512374381253, 9760.219182465456, 10277.588896444207, 3167.9683203167906, 3171.444997125494, 3195.12134163107, 3200.0, 3338.582677165354, 58026.41973580295, 58795.70602146962, 59268.40731592672, 59422.40577594212, 61199.69400153015, 8880.51157186348, 9275.953620231856, 9355.158035776676, 9398.859017114748, 9557.23542116629, 18676.81323186751, 18731.719024214646, 18906.81093189078, 19010.0, 19011.714824277646, 6449.709763060673, 6514.837129071811, 6597.703103360264, 6609.504287178481, 6706.597604143767, 3345.6821601947777, 3440.1571614953996, 3483.7213022958103, 3496.230829217568, 3500.387432199406, 2928.472874882499, 2976.4939960206816, 2983.925401864971, 3001.8048826826184, 3001.9849900750355, 503.9571636410873, 507.8146476536038, 514.459817191947, 520.3885434614389, 526.2632314759368, 476.9632678910714, 496.2184559319087, 513.8047541934042, 575.5481946671842, 579.4118969246218, 7342.889856652155, 7889.447738658354, 7967.3626109911065, 8073.959630201929, 8476.533790641573, 506.11935232695583, 507.1505228741843, 519.4649511003672, 524.4964833759633, 541.7074779618962, 3794.905127371838, 3812.904677383088, 4040.616141466556, 4447.2439685253585, 4774.498677638868, 61343.38656613466, 61773.382266177665, 63026.0, 64411.35588644147, 64796.67601662056, 55043.0, 55429.445705543236, 55457.168142477894, 55712.72143639337, 58432.0, 50202.49797502051, 50444.49555504398, 50899.74550127226, 50999.0, 51373.48626513762, 8923.286137108886, 9208.677696280598, 9235.722928312161, 9343.859842102238, 9345.345825792401, 33874.49188262227, 34451.65548344485, 35459.29081418409, 35498.64501354953, 35820.820895895355, 39534.80232598934, 40151.0, 40599.39100913548, 41200.1759964793, 43534.78232608816, 10447.634332798316, 10761.0, 10845.546899594729, 10932.617358392417, 10962.725931851768, 11759.570214644567, 12050.965396499714, 12420.565280215149, 12448.0, 12471.314077725812, 11419.371934543347, 11499.367534785333, 11537.596184133516, 11745.060395168537, 12104.09219308573, 11350.489227984926, 11369.147313951653, 11523.481443335239, 11987.64037078889, 12039.578614748441, 11721.179517433698, 11820.22270561945, 11839.704007399883, 11891.82162267584, 12127.575534856238, 8810.251128654136, 9045.954770226106, 9048.502332371783, 9056.501892395981, 9641.903580964101, 9734.610615575297, 9824.705258842247, 9873.50632468388, 9904.554295056596, 9909.45497997617], "value": 6147.980496701018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 115.0, 117.0, 115.0, 114.0, 115.0, 116.0, 118.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 134.0, 185.0, 128.0, 129.0, 129.0, 129.0, 158.0, 130.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 170.0, 171.0, 167.0, 161.0, 164.0, 165.0, 165.0, 164.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 77.0, 107.0, 78.0, 79.0, 78.0, 78.0, 93.0, 78.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 105.0, 85.0, 85.0, 84.0, 105.0, 85.0, 86.0, 106.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 117.0, 114.0, 113.0, 114.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 152.0, 151.0, 154.0, 151.0, 151.0, 151.0, 151.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 65.0, 68.0, 67.0, 66.0, 67.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 86.0, 87.0, 88.0, 88.0, 90.0, 87.0, 87.0, 99.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 150.0, 147.0, 136.0, 150.0, 139.0, 141.0, 185.0, 141.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 188.0, 180.0, 182.0, 188.0, 192.0, 183.0, 183.0, 182.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 71.0, 70.0, 76.0, 75.0, 72.0, 71.0, 71.0, 74.0], "value": 72.3, "unit": "score"}]}]} 19:47:00 INFO - Return code: 0 19:47:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:47:00 ERROR - # TBPL SUCCESS # 19:47:00 INFO - Running post-action listener: _resource_record_post_action 19:47:00 INFO - [mozharness: 2017-02-23 03:47:00.663825Z] Finished run-tests step (success) 19:47:00 INFO - Running post-run listener: _resource_record_post_run 19:47:00 INFO - Total resource usage - Wall time: 1201s; CPU: 14.0%; Read bytes: 4096; Write bytes: 366366720; Read time: 16; Write time: 254364 19:47:00 INFO - TinderboxPrint: CPU usage
13.5% 19:47:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:47:00 INFO - TinderboxPrint: I/O write bytes / time
366,366,720 / 254,364 19:47:00 INFO - TinderboxPrint: CPU idle
8,289.1 (86.5%) 19:47:00 INFO - TinderboxPrint: CPU user
1,236.9 (12.9%) 19:47:00 INFO - TinderboxPrint: Swap in / out
0 / 0 19:47:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 112877568; Read time: 0; Write time: 88976 19:47:00 INFO - run-tests - Wall time: 1185s; CPU: 14.0%; Read bytes: 4096; Write bytes: 253489152; Read time: 16; Write time: 165388 19:47:01 INFO - Running post-run listener: _upload_blobber_files 19:47:01 INFO - Blob upload gear active. 19:47:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:47:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:47:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:05 INFO - (blobuploader) - INFO - Done attempting. 19:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:47:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:06 INFO - (blobuploader) - INFO - Done attempting. 19:47:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:47:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:07 INFO - (blobuploader) - INFO - Done attempting. 19:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:08 INFO - (blobuploader) - INFO - Done attempting. 19:47:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:08 INFO - Return code: 0 19:47:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c69cc7d0554a87bc06d20be73fac216123f42d68c996cc39f51a1beb51d80d810fa1d8e2184d4a2cac8a0e62d6aa6322dea4e6052d633c71ca87c9e3c2487c73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ab524d4fd44940c997a8c18a9781d1555ee76417351a5fc3d39f8139198bbeda56f0d19bf8b2561f4261d2d70b9d952a46896f700f49c3838f75f70a0f1f319", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab9955f6d0c0a84dd08ff16ecf82970a2be13a699b0bbb87803b156728cd6e7135c80a6c57459fdbcffa4a6c4dd8f4c18349a9698daa3088646aa7e801d9d68a"} 19:47:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:08 INFO - Contents: 19:47:08 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c69cc7d0554a87bc06d20be73fac216123f42d68c996cc39f51a1beb51d80d810fa1d8e2184d4a2cac8a0e62d6aa6322dea4e6052d633c71ca87c9e3c2487c73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ab524d4fd44940c997a8c18a9781d1555ee76417351a5fc3d39f8139198bbeda56f0d19bf8b2561f4261d2d70b9d952a46896f700f49c3838f75f70a0f1f319", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab9955f6d0c0a84dd08ff16ecf82970a2be13a699b0bbb87803b156728cd6e7135c80a6c57459fdbcffa4a6c4dd8f4c18349a9698daa3088646aa7e801d9d68a"} 19:47:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:08 INFO - Copying logs to upload dir... 19:47:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.031098 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-22 19:47:08.036282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.039487) ========= 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-1487820343.228726-702627981 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/c69cc7d0554a87bc06d20be73fac216123f42d68c996cc39f51a1beb51d80d810fa1d8e2184d4a2cac8a0e62d6aa6322dea4e6052d633c71ca87c9e3c2487c73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ab524d4fd44940c997a8c18a9781d1555ee76417351a5fc3d39f8139198bbeda56f0d19bf8b2561f4261d2d70b9d952a46896f700f49c3838f75f70a0f1f319", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab9955f6d0c0a84dd08ff16ecf82970a2be13a699b0bbb87803b156728cd6e7135c80a6c57459fdbcffa4a6c4dd8f4c18349a9698daa3088646aa7e801d9d68a"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011497 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/c69cc7d0554a87bc06d20be73fac216123f42d68c996cc39f51a1beb51d80d810fa1d8e2184d4a2cac8a0e62d6aa6322dea4e6052d633c71ca87c9e3c2487c73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4ab524d4fd44940c997a8c18a9781d1555ee76417351a5fc3d39f8139198bbeda56f0d19bf8b2561f4261d2d70b9d952a46896f700f49c3838f75f70a0f1f319", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d1b12b5f4ae0f91ad460d7e39a01dc1bd3ee81287f19ee18c84be05d1bef4fa79f2387962f4a6a3677b92cf95cb440b32b20ba9bc7012284ecbbbd31dbdc8c52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ab9955f6d0c0a84dd08ff16ecf82970a2be13a699b0bbb87803b156728cd6e7135c80a6c57459fdbcffa4a6c4dd8f4c18349a9698daa3088646aa7e801d9d68a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.064012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.064309) ========= 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-1487820343.228726-702627981 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:08.148597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:08.148877) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:08.149152) ========= ========= Total master_lag: 0.21 =========