builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1487202852.65 results: success (0) revision: e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.651726) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.652192) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.652478) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.669069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.669458) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-15 15:54:12-- 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% 221M=0s 2017-02-15 15:54:12 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118852 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.900587) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.900940) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017275 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.930028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:12.930365) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 --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 e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 --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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-15 15:54:12,994 truncating revision to first 12 chars 2017-02-15 15:54:12,994 Setting DEBUG logging. 2017-02-15 15:54:12,994 attempt 1/10 2017-02-15 15:54:12,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e783bdf2cb50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-15 15:54:13,316 unpacking tar archive at: mozilla-central-e783bdf2cb50/testing/mozharness/ program finished with exit code 0 elapsedTime=0.709614 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:13.653777) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:13.654306) ========= script_repo_revision: e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:13.654875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:13.655144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-15 15:54:13.664875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-15 15:54:13.665400) ========= /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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:54:13 INFO - MultiFileLogger online at 20170215 15:54:13 in /builds/slave/test 15:54:13 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 15:54:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:13 INFO - {'append_to_log': False, 15:54:13 INFO - 'base_work_dir': '/builds/slave/test', 15:54:13 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:54:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:13 INFO - 'branch': 'Firefox-Non-PGO', 15:54:13 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:13 INFO - 'config_files': ('talos/linux_config.py',), 15:54:13 INFO - 'default_actions': ('clobber', 15:54:13 INFO - 'read-buildbot-config', 15:54:13 INFO - 'download-and-extract', 15:54:13 INFO - 'populate-webroot', 15:54:13 INFO - 'create-virtualenv', 15:54:13 INFO - 'install', 15:54:13 INFO - 'run-tests'), 15:54:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:13 INFO - 'download_minidump_stackwalk': True, 15:54:13 INFO - 'download_symbols': 'ondemand', 15:54:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:13 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:13 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:13 INFO - 'installer_path': 'installer.exe', 15:54:13 INFO - 'log_level': 'info', 15:54:13 INFO - 'log_name': 'talos', 15:54:13 INFO - 'log_to_console': True, 15:54:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:54:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:54:13 INFO - 'opt_config_files': (), 15:54:13 INFO - 'pip_index': False, 15:54:13 INFO - 'sps_profile': False, 15:54:13 INFO - 'sps_profile_interval': 0, 15:54:13 INFO - 'suite': 'dromaeojs-e10s', 15:54:13 INFO - 'system_bits': '32', 15:54:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:13 INFO - 'title': 'talos-linux64-ix-044', 15:54:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:13 INFO - 'use_talos_json': True, 15:54:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:13 INFO - 'work_dir': 'build'} 15:54:13 INFO - [mozharness: 2017-02-15 23:54:13.829784Z] Running clobber step. 15:54:13 INFO - Running pre-action listener: _resource_record_pre_action 15:54:13 INFO - Running main action method: clobber 15:54:13 INFO - rmtree: /builds/slave/test/build 15:54:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:15 INFO - Running post-action listener: _resource_record_post_action 15:54:15 INFO - [mozharness: 2017-02-15 23:54:15.458824Z] Finished clobber step (success) 15:54:15 INFO - [mozharness: 2017-02-15 23:54:15.458923Z] Running read-buildbot-config step. 15:54:15 INFO - Running pre-action listener: _resource_record_pre_action 15:54:15 INFO - Running main action method: read_buildbot_config 15:54:15 INFO - Using buildbot properties: 15:54:15 INFO - { 15:54:15 INFO - "project": "", 15:54:15 INFO - "product": "firefox", 15:54:15 INFO - "who": "kwierso@gmail.com", 15:54:15 INFO - "installer_path": "public/build/target.tar.bz2", 15:54:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:54:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:54:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:54:15 INFO - "stage_platform": "linux64", 15:54:15 INFO - "basedir": "/builds/slave/test", 15:54:15 INFO - "buildnumber": 700, 15:54:15 INFO - "platform": "ubuntu64_hw", 15:54:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:54:15 INFO - "slavebuilddir": "test", 15:54:15 INFO - "taskId": "c0pXk6UpQaOYeG3s8lDUXA", 15:54:15 INFO - "branch": "mozilla-central", 15:54:15 INFO - "script_repo_revision": "production", 15:54:15 INFO - "revision": "e783bdf2cb500b1b23aadc3957c59264b3d2c6e7", 15:54:15 INFO - "slavename": "talos-linux64-ix-044", 15:54:15 INFO - "repo_path": "mozilla-central" 15:54:15 INFO - } 15:54:15 INFO - Finding installer, test and symbols from parent task. 15:54:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c0pXk6UpQaOYeG3s8lDUXA'}, attempt #1 15:54:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c0pXk6UpQaOYeG3s8lDUXA'}, attempt #1 15:54:16 INFO - Task dependencies: Znn-nuAmQAmBsG85TGBJSg 15:54:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg'}, attempt #1 15:54:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 15:54:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/test_packages.json 15:54:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.crashreporter-symbols.zip 15:54:16 INFO - Running post-action listener: _resource_record_post_action 15:54:16 INFO - [mozharness: 2017-02-15 23:54:16.833368Z] Finished read-buildbot-config step (success) 15:54:16 INFO - [mozharness: 2017-02-15 23:54:16.833620Z] Running download-and-extract step. 15:54:16 INFO - Running pre-action listener: _resource_record_pre_action 15:54:16 INFO - Running main action method: download_and_extract 15:54:16 INFO - mkdir: /builds/slave/test/build/tests 15:54:16 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')]} 15:54:16 INFO - trying https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.test_packages.json 15:54:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:54:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:54:17 INFO - Downloaded 896 bytes. 15:54:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:54:17 INFO - Using the following test package requirements: 15:54:17 INFO - {u'common': [u'target.common.tests.zip'], 15:54:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:54:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:54:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:54:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:54:17 INFO - u'mozbase': [u'target.common.tests.zip'], 15:54:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:54:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:54:17 INFO - u'web-platform': [u'target.common.tests.zip', 15:54:17 INFO - u'target.web-platform.tests.zip'], 15:54:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:54:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:54:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip 15:54:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:54:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip into memory 15:54:18 INFO - Expected file size: 57026093 15:54:18 INFO - Obtained file size: 57026093 15:54:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:54:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip 15:54:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:54:28 INFO - Fetch https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.common.tests.zip into memory 15:54:29 INFO - Expected file size: 57026093 15:54:29 INFO - Obtained file size: 57026093 15:54:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.talos.tests.zip 15:54:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:54:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.talos.tests.zip into memory 15:54:40 INFO - Expected file size: 14403647 15:54:40 INFO - Obtained file size: 14403647 15:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:40 INFO - trying https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 15:54:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:54:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:54:41 INFO - Downloaded 56470688 bytes. 15:54:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 15:54:41 INFO - mkdir: /builds/slave/test/properties 15:54:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:54:41 INFO - Writing to file /builds/slave/test/properties/build_url 15:54:41 INFO - Contents: 15:54:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 15:54:41 INFO - Running post-action listener: _resource_record_post_action 15:54:41 INFO - Running post-action listener: set_extra_try_arguments 15:54:41 INFO - [mozharness: 2017-02-15 23:54:41.701823Z] Finished download-and-extract step (success) 15:54:41 INFO - [mozharness: 2017-02-15 23:54:41.701919Z] Running populate-webroot step. 15:54:41 INFO - Running pre-action listener: _resource_record_pre_action 15:54:41 INFO - Running main action method: populate_webroot 15:54:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:54:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:54:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:54:41 INFO - '--tppagecycles', 15:54:41 INFO - '7'], 15:54:41 INFO - 'tests': ['tsvgm']}}, 15:54:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['damp', 'tps']}, 15:54:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'tests': ['damp', 'tps']}, 15:54:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['dromaeo_dom']}, 15:54:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['basic_compositor_video']}, 15:54:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:54:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['a11yr', 15:54:41 INFO - 'ts_paint', 15:54:41 INFO - 'tpaint', 15:54:41 INFO - 'sessionrestore', 15:54:41 INFO - 'sessionrestore_no_auto_restore', 15:54:41 INFO - 'tabpaint']}, 15:54:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['tsvgx', 15:54:41 INFO - 'tsvgr_opacity', 15:54:41 INFO - 'tart', 15:54:41 INFO - 'tscrollx', 15:54:41 INFO - 'cart', 15:54:41 INFO - 'tsvg_static']}, 15:54:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:41 INFO - 'tsvgr_opacity', 15:54:41 INFO - 'tart', 15:54:41 INFO - 'tscrollx', 15:54:41 INFO - 'cart', 15:54:41 INFO - 'tsvg_static']}, 15:54:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'talos_options': ['--disable-e10s'], 15:54:41 INFO - 'tests': ['tp5o']}, 15:54:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:54:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'talos_options': ['--disable-e10s', 15:54:41 INFO - '--xperf_path', 15:54:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:41 INFO - 'tests': ['tp5n']}, 15:54:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:54:41 INFO - 'talos_options': ['--xperf_path', 15:54:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:41 INFO - 'tests': ['tp5n']}}, 15:54:41 INFO - 'talos.zip': {'path': '', 15:54:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:54:41 INFO - Running post-action listener: _resource_record_post_action 15:54:41 INFO - [mozharness: 2017-02-15 23:54:41.715079Z] Finished populate-webroot step (success) 15:54:41 INFO - [mozharness: 2017-02-15 23:54:41.715169Z] Running create-virtualenv step. 15:54:41 INFO - Running pre-action listener: _resource_record_pre_action 15:54:41 INFO - Running main action method: create_virtualenv 15:54:41 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:41 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 15:54:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:54:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:41 INFO - Using real prefix '/usr' 15:54:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:42 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:44 INFO - Installing pip.................done. 15:54:44 INFO - Return code: 0 15:54:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:54:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:54:44 INFO - Reading from file tmpfile_stdout 15:54:44 INFO - Output received: 15:54:44 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) 15:54:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:44 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')]} 15:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:44 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')]} 15:54:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:44 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:44 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 15:54:44 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 15:54:44 INFO - Using env: {'DISPLAY': ':0', 15:54:44 INFO - 'HOME': '/home/cltbld', 15:54:44 INFO - 'LANG': 'en_US.UTF-8', 15:54:44 INFO - 'LANGUAGE': 'en_US:en', 15:54:44 INFO - 'LOGNAME': 'cltbld', 15:54:44 INFO - 'MAIL': '/var/mail/cltbld', 15:54:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:44 INFO - 'MOZ_NO_REMOTE': '1', 15:54:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:44 INFO - 'NO_EM_RESTART': '1', 15:54:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:44 INFO - 'PWD': '/builds/slave/test', 15:54:44 INFO - 'SHELL': '/bin/bash', 15:54:44 INFO - 'SHLVL': '1', 15:54:44 INFO - 'TERM': 'linux', 15:54:44 INFO - 'TMOUT': '86400', 15:54:44 INFO - 'USER': 'cltbld', 15:54:44 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:44 INFO - '_': '/tools/buildbot/bin/python'} 15:54:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:44 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 15:54:44 INFO - Cleaning up... 15:54:44 INFO - Return code: 0 15:54:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:44 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')]} 15:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:44 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')]} 15:54:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:44 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:44 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 15:54:44 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 15:54:44 INFO - Using env: {'DISPLAY': ':0', 15:54:44 INFO - 'HOME': '/home/cltbld', 15:54:44 INFO - 'LANG': 'en_US.UTF-8', 15:54:44 INFO - 'LANGUAGE': 'en_US:en', 15:54:44 INFO - 'LOGNAME': 'cltbld', 15:54:44 INFO - 'MAIL': '/var/mail/cltbld', 15:54:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:44 INFO - 'MOZ_NO_REMOTE': '1', 15:54:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:44 INFO - 'NO_EM_RESTART': '1', 15:54:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:44 INFO - 'PWD': '/builds/slave/test', 15:54:44 INFO - 'SHELL': '/bin/bash', 15:54:44 INFO - 'SHLVL': '1', 15:54:44 INFO - 'TERM': 'linux', 15:54:44 INFO - 'TMOUT': '86400', 15:54:44 INFO - 'USER': 'cltbld', 15:54:44 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:44 INFO - '_': '/tools/buildbot/bin/python'} 15:54:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:44 INFO - Downloading/unpacking psutil>=3.1.1 15:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:47 INFO - Installing collected packages: psutil 15:54:47 INFO - Running setup.py install for psutil 15:54:47 INFO - building 'psutil._psutil_linux' extension 15:54:47 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 15:54:47 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 15:54:47 INFO - building 'psutil._psutil_posix' extension 15:54:47 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 15:54:47 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 15:54:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:47 INFO - Successfully installed psutil 15:54:47 INFO - Cleaning up... 15:54:47 INFO - Return code: 0 15:54:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:54: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 mozsystemmonitor==0.3 15:54:47 INFO - Using env: {'DISPLAY': ':0', 15:54:47 INFO - 'HOME': '/home/cltbld', 15:54:47 INFO - 'LANG': 'en_US.UTF-8', 15:54:47 INFO - 'LANGUAGE': 'en_US:en', 15:54:47 INFO - 'LOGNAME': 'cltbld', 15:54:47 INFO - 'MAIL': '/var/mail/cltbld', 15:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:47 INFO - 'MOZ_NO_REMOTE': '1', 15:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:47 INFO - 'NO_EM_RESTART': '1', 15:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:47 INFO - 'PWD': '/builds/slave/test', 15:54:47 INFO - 'SHELL': '/bin/bash', 15:54:47 INFO - 'SHLVL': '1', 15:54:47 INFO - 'TERM': 'linux', 15:54:47 INFO - 'TMOUT': '86400', 15:54:47 INFO - 'USER': 'cltbld', 15:54:47 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:47 INFO - '_': '/tools/buildbot/bin/python'} 15:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:49 INFO - Installing collected packages: mozsystemmonitor 15:54:49 INFO - Running setup.py install for mozsystemmonitor 15:54:49 INFO - Successfully installed mozsystemmonitor 15:54:49 INFO - Cleaning up... 15:54:50 INFO - Return code: 0 15:54:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:50 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:50 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 15:54:50 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 15:54:50 INFO - Using env: {'DISPLAY': ':0', 15:54:50 INFO - 'HOME': '/home/cltbld', 15:54:50 INFO - 'LANG': 'en_US.UTF-8', 15:54:50 INFO - 'LANGUAGE': 'en_US:en', 15:54:50 INFO - 'LOGNAME': 'cltbld', 15:54:50 INFO - 'MAIL': '/var/mail/cltbld', 15:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:50 INFO - 'MOZ_NO_REMOTE': '1', 15:54:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:50 INFO - 'NO_EM_RESTART': '1', 15:54:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:50 INFO - 'PWD': '/builds/slave/test', 15:54:50 INFO - 'SHELL': '/bin/bash', 15:54:50 INFO - 'SHLVL': '1', 15:54:50 INFO - 'TERM': 'linux', 15:54:50 INFO - 'TMOUT': '86400', 15:54:50 INFO - 'USER': 'cltbld', 15:54:50 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:50 INFO - '_': '/tools/buildbot/bin/python'} 15:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:50 INFO - Downloading/unpacking jsonschema==2.5.1 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54: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 15:54:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:52 INFO - warning: no files found matching '*.txt' 15:54:52 INFO - no previously-included directories found matching 'build' 15:54:52 INFO - no previously-included directories found matching 'dist' 15:54:52 INFO - no previously-included directories found matching '.git*' 15:54:52 INFO - Installing collected packages: jsonschema, functools32 15:54:52 INFO - Running setup.py install for functools32 15:54:52 INFO - warning: no files found matching '*.txt' 15:54:52 INFO - no previously-included directories found matching 'build' 15:54:52 INFO - no previously-included directories found matching 'dist' 15:54:52 INFO - no previously-included directories found matching '.git*' 15:54:52 INFO - Successfully installed jsonschema functools32 15:54:52 INFO - Cleaning up... 15:54:52 INFO - Return code: 0 15:54:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:52 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')]} 15:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 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')]} 15:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:52 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 15:54:52 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 15:54:52 INFO - Using env: {'DISPLAY': ':0', 15:54:52 INFO - 'HOME': '/home/cltbld', 15:54:52 INFO - 'LANG': 'en_US.UTF-8', 15:54:52 INFO - 'LANGUAGE': 'en_US:en', 15:54:52 INFO - 'LOGNAME': 'cltbld', 15:54:52 INFO - 'MAIL': '/var/mail/cltbld', 15:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:52 INFO - 'MOZ_NO_REMOTE': '1', 15:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:52 INFO - 'NO_EM_RESTART': '1', 15:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:52 INFO - 'PWD': '/builds/slave/test', 15:54:52 INFO - 'SHELL': '/bin/bash', 15:54:52 INFO - 'SHLVL': '1', 15:54:52 INFO - 'TERM': 'linux', 15:54:52 INFO - 'TMOUT': '86400', 15:54:52 INFO - 'USER': 'cltbld', 15:54:52 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:52 INFO - '_': '/tools/buildbot/bin/python'} 15:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:52 INFO - Cleaning up... 15:54:53 INFO - Return code: 0 15:54:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:53 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:53 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 15:54:53 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 15:54:53 INFO - Using env: {'DISPLAY': ':0', 15:54:53 INFO - 'HOME': '/home/cltbld', 15:54:53 INFO - 'LANG': 'en_US.UTF-8', 15:54:53 INFO - 'LANGUAGE': 'en_US:en', 15:54:53 INFO - 'LOGNAME': 'cltbld', 15:54:53 INFO - 'MAIL': '/var/mail/cltbld', 15:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:53 INFO - 'MOZ_NO_REMOTE': '1', 15:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:53 INFO - 'NO_EM_RESTART': '1', 15:54:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:53 INFO - 'PWD': '/builds/slave/test', 15:54:53 INFO - 'SHELL': '/bin/bash', 15:54:53 INFO - 'SHLVL': '1', 15:54:53 INFO - 'TERM': 'linux', 15:54:53 INFO - 'TMOUT': '86400', 15:54:53 INFO - 'USER': 'cltbld', 15:54:53 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:53 INFO - '_': '/tools/buildbot/bin/python'} 15:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:53 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:53 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 15:54:53 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 15:54:53 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 15:54:53 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 15:54:53 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 15:54:53 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 15:54:55 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:55 INFO - Downloading docopt-0.6.1.tar.gz 15:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:56 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:56 INFO - Running setup.py install for blobuploader 15:54:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:56 INFO - Running setup.py install for requests 15:54:56 INFO - Running setup.py install for docopt 15:54:56 INFO - Successfully installed blobuploader requests docopt 15:54:56 INFO - Cleaning up... 15:54:56 INFO - Return code: 0 15:54:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:56 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')]} 15:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:56 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')]} 15:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:56 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:56 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 15:54:56 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 15:54:56 INFO - Using env: {'DISPLAY': ':0', 15:54:56 INFO - 'HOME': '/home/cltbld', 15:54:56 INFO - 'LANG': 'en_US.UTF-8', 15:54:56 INFO - 'LANGUAGE': 'en_US:en', 15:54:56 INFO - 'LOGNAME': 'cltbld', 15:54:56 INFO - 'MAIL': '/var/mail/cltbld', 15:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:56 INFO - 'MOZ_NO_REMOTE': '1', 15:54:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:56 INFO - 'NO_EM_RESTART': '1', 15:54:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:56 INFO - 'PWD': '/builds/slave/test', 15:54:56 INFO - 'SHELL': '/bin/bash', 15:54:56 INFO - 'SHLVL': '1', 15:54:56 INFO - 'TERM': 'linux', 15:54:56 INFO - 'TMOUT': '86400', 15:54:56 INFO - 'USER': 'cltbld', 15:54:56 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:56 INFO - '_': '/tools/buildbot/bin/python'} 15:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:56 INFO - Running setup.py (path:/tmp/pip-l05elP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:56 INFO - Running setup.py (path:/tmp/pip-hOEzOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:56 INFO - Running setup.py (path:/tmp/pip-76Fcv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:57 INFO - Running setup.py (path:/tmp/pip-tTqvvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:57 INFO - Running setup.py (path:/tmp/pip-vZU2Z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:57 INFO - Running setup.py (path:/tmp/pip-THlWyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:57 INFO - Running setup.py (path:/tmp/pip-zHnvAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:57 INFO - Running setup.py (path:/tmp/pip-3w6euK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:57 INFO - Running setup.py (path:/tmp/pip-Euxzz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:57 INFO - Running setup.py (path:/tmp/pip-t4klFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:57 INFO - Running setup.py (path:/tmp/pip-avhUBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:57 INFO - Running setup.py (path:/tmp/pip-yPTcu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:57 INFO - Running setup.py (path:/tmp/pip-jCf7JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:57 INFO - Running setup.py (path:/tmp/pip-rCfCAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:57 INFO - Running setup.py (path:/tmp/pip-3HFoC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:57 INFO - Running setup.py (path:/tmp/pip-9Vd1lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:58 INFO - Running setup.py (path:/tmp/pip-XTn2HZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:58 INFO - Running setup.py install for manifestparser 15:54:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Running setup.py install for mozcrash 15:54:58 INFO - Running setup.py install for mozdebug 15:54:58 INFO - Running setup.py install for mozdevice 15:54:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Running setup.py install for mozfile 15:54:58 INFO - Running setup.py install for mozhttpd 15:54:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Running setup.py install for mozinfo 15:54:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Running setup.py install for mozInstall 15:54:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:58 INFO - Running setup.py install for mozleak 15:54:58 INFO - Running setup.py install for mozlog 15:54:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Running setup.py install for moznetwork 15:54:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Running setup.py install for mozprocess 15:54:59 INFO - Running setup.py install for mozprofile 15:54:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Running setup.py install for mozrunner 15:54:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Running setup.py install for mozscreenshot 15:54:59 INFO - Running setup.py install for moztest 15:54:59 INFO - Running setup.py install for mozversion 15:54:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:59 INFO - Cleaning up... 15:54:59 INFO - Return code: 0 15:54:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:59 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:54:59 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 15:54:59 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 15:54:59 INFO - Using env: {'DISPLAY': ':0', 15:54:59 INFO - 'HOME': '/home/cltbld', 15:54:59 INFO - 'LANG': 'en_US.UTF-8', 15:54:59 INFO - 'LANGUAGE': 'en_US:en', 15:54:59 INFO - 'LOGNAME': 'cltbld', 15:54:59 INFO - 'MAIL': '/var/mail/cltbld', 15:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:59 INFO - 'MOZ_NO_REMOTE': '1', 15:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:59 INFO - 'NO_EM_RESTART': '1', 15:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:59 INFO - 'PWD': '/builds/slave/test', 15:54:59 INFO - 'SHELL': '/bin/bash', 15:54:59 INFO - 'SHLVL': '1', 15:54:59 INFO - 'TERM': 'linux', 15:54:59 INFO - 'TMOUT': '86400', 15:54:59 INFO - 'USER': 'cltbld', 15:54:59 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:59 INFO - '_': '/tools/buildbot/bin/python'} 15:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:00 INFO - Running setup.py (path:/tmp/pip-Vk5vNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:00 INFO - Running setup.py (path:/tmp/pip-ti6MtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:00 INFO - Running setup.py (path:/tmp/pip-WE1vqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:00 INFO - Running setup.py (path:/tmp/pip-eIizey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:00 INFO - Running setup.py (path:/tmp/pip-DpuKWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:00 INFO - Running setup.py (path:/tmp/pip-iHdGEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:00 INFO - Running setup.py (path:/tmp/pip-tdcM27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:00 INFO - Running setup.py (path:/tmp/pip-9rROp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:00 INFO - Running setup.py (path:/tmp/pip-YHByJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:00 INFO - Running setup.py (path:/tmp/pip-aFjOGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:00 INFO - Running setup.py (path:/tmp/pip-sjf2ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:00 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)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:00 INFO - Running setup.py (path:/tmp/pip-e9i3va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:00 INFO - Running setup.py (path:/tmp/pip-_dFaFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:01 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)) 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:01 INFO - Running setup.py (path:/tmp/pip-8p9oy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:01 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)) 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:01 INFO - Running setup.py (path:/tmp/pip-R5bhUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:01 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)) 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:01 INFO - Running setup.py (path:/tmp/pip-X_7TCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:01 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)) 15:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:01 INFO - Running setup.py (path:/tmp/pip-8NaBvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:01 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)) 15:55:01 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)) 15:55:01 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)) 15:55:01 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)) 15:55:01 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)) 15:55:01 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)) 15:55:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:01 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 15:55:01 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 15:55:01 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 15:55:01 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 15:55:01 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 15:55:01 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 15:55:03 INFO - Downloading blessings-1.6.tar.gz 15:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:03 INFO - Installing collected packages: blessings 15:55:03 INFO - Running setup.py install for blessings 15:55:03 INFO - Successfully installed blessings 15:55:03 INFO - Cleaning up... 15:55:03 INFO - Return code: 0 15:55:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:03 INFO - Reading from file tmpfile_stdout 15:55:03 INFO - Current package versions: 15:55:03 INFO - argparse == 1.2.1 15:55:03 INFO - blessings == 1.6 15:55:03 INFO - blobuploader == 1.2.4 15:55:03 INFO - docopt == 0.6.1 15:55:03 INFO - functools32 == 3.2.3-2 15:55:03 INFO - jsonschema == 2.5.1 15:55:03 INFO - manifestparser == 1.1 15:55:03 INFO - mozInstall == 1.12 15:55:03 INFO - mozcrash == 1.0 15:55:03 INFO - mozdebug == 0.1 15:55:03 INFO - mozdevice == 0.49 15:55:03 INFO - mozfile == 1.2 15:55:03 INFO - mozhttpd == 0.7 15:55:03 INFO - mozinfo == 0.9 15:55:03 INFO - mozleak == 0.1 15:55:03 INFO - mozlog == 3.4 15:55:03 INFO - moznetwork == 0.27 15:55:03 INFO - mozprocess == 0.24 15:55:03 INFO - mozprofile == 0.28 15:55:03 INFO - mozrunner == 6.13 15:55:03 INFO - mozscreenshot == 0.1 15:55:03 INFO - mozsystemmonitor == 0.3 15:55:03 INFO - moztest == 0.8 15:55:03 INFO - mozversion == 1.4 15:55:03 INFO - psutil == 3.1.1 15:55:03 INFO - requests == 1.2.3 15:55:03 INFO - wsgiref == 0.1.2 15:55:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:03 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:55:03 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 15:55:03 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 15:55:03 INFO - Using env: {'DISPLAY': ':0', 15:55:03 INFO - 'HOME': '/home/cltbld', 15:55:03 INFO - 'LANG': 'en_US.UTF-8', 15:55:03 INFO - 'LANGUAGE': 'en_US:en', 15:55:03 INFO - 'LOGNAME': 'cltbld', 15:55:03 INFO - 'MAIL': '/var/mail/cltbld', 15:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:03 INFO - 'MOZ_NO_REMOTE': '1', 15:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:03 INFO - 'NO_EM_RESTART': '1', 15:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:03 INFO - 'PWD': '/builds/slave/test', 15:55:03 INFO - 'SHELL': '/bin/bash', 15:55:03 INFO - 'SHLVL': '1', 15:55:03 INFO - 'TERM': 'linux', 15:55:03 INFO - 'TMOUT': '86400', 15:55:03 INFO - 'USER': 'cltbld', 15:55:03 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:03 INFO - '_': '/tools/buildbot/bin/python'} 15:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 INFO - Cleaning up... 15:55:04 INFO - Return code: 0 15:55:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:04 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')]} 15:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:04 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')]} 15:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:04 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 0x19671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', '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 15:55:04 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 15:55:04 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 15:55:04 INFO - Using env: {'DISPLAY': ':0', 15:55:04 INFO - 'HOME': '/home/cltbld', 15:55:04 INFO - 'LANG': 'en_US.UTF-8', 15:55:04 INFO - 'LANGUAGE': 'en_US:en', 15:55:04 INFO - 'LOGNAME': 'cltbld', 15:55:04 INFO - 'MAIL': '/var/mail/cltbld', 15:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:04 INFO - 'MOZ_NO_REMOTE': '1', 15:55:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:04 INFO - 'NO_EM_RESTART': '1', 15:55:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:04 INFO - 'PWD': '/builds/slave/test', 15:55:04 INFO - 'SHELL': '/bin/bash', 15:55:04 INFO - 'SHLVL': '1', 15:55:04 INFO - 'TERM': 'linux', 15:55:04 INFO - 'TMOUT': '86400', 15:55:04 INFO - 'USER': 'cltbld', 15:55:04 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:04 INFO - '_': '/tools/buildbot/bin/python'} 15:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:04 INFO - Cleaning up... 15:55:04 INFO - Return code: 0 15:55:04 INFO - Running post-action listener: _resource_record_post_action 15:55:04 INFO - Running post-action listener: _start_resource_monitoring 15:55:04 INFO - Starting resource monitoring. 15:55:04 INFO - [mozharness: 2017-02-15 23:55:04.325259Z] Finished create-virtualenv step (success) 15:55:04 INFO - [mozharness: 2017-02-15 23:55:04.325940Z] Running install step. 15:55:04 INFO - Running pre-action listener: _resource_record_pre_action 15:55:04 INFO - Running main action method: install 15:55:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:04 INFO - Reading from file tmpfile_stdout 15:55:04 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:04 INFO - Reading from file tmpfile_stdout 15:55:04 INFO - Output received: 15:55:04 INFO - Usage: mozinstall [options] installer 15:55:04 INFO - Options: 15:55:04 INFO - -h, --help show this help message and exit 15:55:04 INFO - -d DEST, --destination=DEST 15:55:04 INFO - Directory to install application into. [default: 15:55:04 INFO - "/builds/slave/test"] 15:55:04 INFO - --app=APP Application being installed. [default: firefox] 15:55:04 INFO - mkdir: /builds/slave/test/build/application 15:55:04 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'] 15:55:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:55:20 INFO - Reading from file tmpfile_stdout 15:55:20 INFO - Output received: 15:55:20 INFO - /builds/slave/test/build/application/firefox/firefox 15:55:20 INFO - Running post-action listener: _resource_record_post_action 15:55:20 INFO - [mozharness: 2017-02-15 23:55:20.914145Z] Finished install step (success) 15:55:20 INFO - [mozharness: 2017-02-15 23:55:20.914483Z] Running run-tests step. 15:55:20 INFO - Running pre-action listener: _resource_record_pre_action 15:55:20 INFO - Running main action method: run_tests 15:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:55:20 INFO - Python 2.7.3 15:55:20 INFO - Return code: 0 15:55:20 INFO - grabbing minidump binary from tooltool 15:55:20 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')]} 15:55:20 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 0x1c02290>, '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 0x1c02b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c03040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:55:20 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 15:55:20 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 15:55:20 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 15:55:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:55:20 INFO - Return code: 0 15:55:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:55:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:55:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:55:20 INFO - ENV: RUST_BACKTRACE is now 1 15:55:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:55:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:55:20 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/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 15:55:20 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/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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 15:55:20 INFO - Using env: {'DISPLAY': ':0', 15:55:20 INFO - 'HOME': '/home/cltbld', 15:55:20 INFO - 'LANG': 'en_US.UTF-8', 15:55:20 INFO - 'LANGUAGE': 'en_US:en', 15:55:20 INFO - 'LOGNAME': 'cltbld', 15:55:20 INFO - 'MAIL': '/var/mail/cltbld', 15:55:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:55:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:55:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:21 INFO - 'MOZ_NO_REMOTE': '1', 15:55:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:55:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:21 INFO - 'NO_EM_RESTART': '1', 15:55:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:21 INFO - 'PWD': '/builds/slave/test', 15:55:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:55:21 INFO - 'RUST_BACKTRACE': '1', 15:55:21 INFO - 'SHELL': '/bin/bash', 15:55:21 INFO - 'SHLVL': '1', 15:55:21 INFO - 'TERM': 'linux', 15:55:21 INFO - 'TMOUT': '86400', 15:55:21 INFO - 'USER': 'cltbld', 15:55:21 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462', 15:55:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:21 INFO - '_': '/tools/buildbot/bin/python'} 15:55:21 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/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 15:55:21 INFO - mozversion application_buildid: 20170215232619 15:55:21 INFO - mozversion application_changeset: e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 15:55:21 INFO - mozversion application_display_name: Nightly 15:55:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:55:21 INFO - mozversion application_name: Firefox 15:55:21 INFO - mozversion application_remotingname: firefox 15:55:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:55:21 INFO - mozversion application_vendor: Mozilla 15:55:21 INFO - mozversion application_version: 54.0a1 15:55:21 INFO - mozversion platform_buildid: 20170215232619 15:55:21 INFO - mozversion platform_changeset: e783bdf2cb500b1b23aadc3957c59264b3d2c6e7 15:55:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:55:21 INFO - mozversion platform_version: 54.0a1 15:55:21 INFO - using testdate: 1487202921 15:55:21 INFO - actual date: 1487202921 15:55:21 INFO - starting webserver on 'localhost:34793' 15:55:21 INFO - SUITE-START | Running 2 tests 15:55:21 INFO - TEST-START | dromaeo_css 15:55:21 INFO - operating with platform_type : linux_ 15:55:21 INFO - Initialising browser for dromaeo_css test... 15:55:21 INFO - TEST-INFO | started process 17599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMhUSLa/profile http://localhost:34793/getInfo.html) 15:55:24 INFO - PROCESS | 17599 | Iterating over profile /tmp/tmpMhUSLa/profile 15:55:24 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:24 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/places.sqlite-shm = Wed Feb 15 2017 15:55:22 GMT-0800 (PST) 15:55:24 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:24 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/crashes = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:24 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:24 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/minidumps = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:24 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:24 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/extensions.json = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:24 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:24 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/sessionCheckpoints.json = Wed Feb 15 2017 15:55:24 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/key3.db = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/permissions.sqlite = Wed Feb 15 2017 15:55:22 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/user.js = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | __metrics Screen width/height:1600/1200 15:55:25 INFO - PROCESS | 17599 | colorDepth:24 15:55:25 INFO - PROCESS | 17599 | Browser inner width/height: 1024/697 15:55:25 INFO - PROCESS | 17599 | __metrics 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/.parentlock = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/cert8.db = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/compatibility.ini = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/places.sqlite = Wed Feb 15 2017 15:55:25 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/startupCache = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/pluginreg.dat = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/directoryLinks.json = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/prefs.js = Wed Feb 15 2017 15:54:40 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/thumbnails = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/addons.json = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/places.sqlite-wal = Wed Feb 15 2017 15:55:25 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | Stat failure 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/safebrowsing = Wed Feb 15 2017 15:55:25 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/secmod.db = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/extensions.ini = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/cache2 = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/localstore.rdf = Wed Feb 15 2017 15:54:40 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/content-prefs.sqlite = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/frequencyCap.json = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/gmp = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/extensions = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/bookmarkbackups = Wed Feb 15 2017 15:55:23 GMT-0800 (PST) 15:55:25 INFO - PROCESS | 17599 | No birth date. Using mtime. 15:55:25 INFO - PROCESS | 17599 | Using date: /tmp/tmpMhUSLa/profile/blocklist.xml = Wed Feb 15 2017 15:55:21 GMT-0800 (PST) 15:55:26 ERROR - PROCESS | 17599 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 15:55:27 INFO - TEST-INFO | 17599: exit 0 15:55:27 INFO - Browser initialized. 15:55:27 INFO - Running cycle 1/1 for dromaeo_css test... 15:55:27 INFO - TEST-INFO | started process 17720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMhUSLa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:39 INFO - PROCESS | 17720 | RSS: Main: 160362496 15:55:39 INFO - PROCESS | 17720 | 15:58:44 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34793/tests/dromaeo/cssquery-ext.html) 15:58:45 INFO - PROCESS | 17720 | RSS: Main: 172429312 15:58:45 INFO - PROCESS | 17720 | 16:00:27 INFO - PROCESS | 17720 | ************************* 16:00:27 INFO - PROCESS | 17720 | A coding exception was thrown and uncaught in a Task. 16:00:27 INFO - PROCESS | 17720 | 16:00:27 ERROR - PROCESS | 17720 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:00:27 INFO - PROCESS | 17720 | Full stack: 16:00:27 INFO - PROCESS | 17720 | ************************* 16:01:48 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-ext.html (next: http://localhost:34793/tests/dromaeo/cssquery-jquery.html) 16:01:48 INFO - PROCESS | 17720 | RSS: Main: 166277120 16:01:48 INFO - PROCESS | 17720 | 16:04:50 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34793/tests/dromaeo/cssquery-mootools.html) 16:04:51 INFO - PROCESS | 17720 | RSS: Main: 173371392 16:04:51 INFO - PROCESS | 17720 | 16:07:53 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34793/tests/dromaeo/cssquery-prototype.html) 16:07:54 INFO - PROCESS | 17720 | RSS: Main: 166522880 16:07:54 INFO - PROCESS | 17720 | 16:10:57 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34793/tests/dromaeo/cssquery-yui.html) 16:10:57 INFO - PROCESS | 17720 | RSS: Main: 171233280 16:10:57 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | Cycle 1(1): loaded http://localhost:34793/tests/dromaeo/cssquery-yui.html (next: http://localhost:34793/tests/dromaeo/cssquery-dojo.html) 16:14:00 INFO - PROCESS | 17720 | RSS: Main: 169881600 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | __start_tp_report 16:14:00 INFO - PROCESS | 17720 | _x_x_mozilla_page_load 16:14:00 INFO - PROCESS | 17720 | _x_x_mozilla_page_load_details 16:14:00 INFO - PROCESS | 17720 | |i|pagename|runs| 16:14:00 INFO - PROCESS | 17720 | |0;dojo.html;2616.947661046779;2650.655414796076;2667.5865240887656;2669.5328317544436;2679.5310820606387;4603.125406172825;4845.539673730995;4868.780904859276;4896.926546101807;4908.141075311815;4637.003044345465;4813.2780082987565;4827.56551910328;4832;4915.6067514598835;4224.5353011168745;4342.826286948518;4395.7362558246605;4425.446819147606;4618.630509559244;4551.704139230956;4582.885427864297;4619.237825758746;4619.74591397473;4635.003474253035;4276;4564.383808185886;4585.6102231310215;4604.194266003469;4639.489656137839;4323.6757243206885;4344.391785150082;4381.145676593065;4402.471703395613;4445.666575006888;4281.400603915455;4371.256886329332;4383.495897971742;4437.090396468716;4443.2224360736745;4704.6941948773565;4740.525947405266;4767.451743049524;4769.332293478916;4820.325154478376;4953.182724850395;4963.578095861856;4970.453250142481;5080.923786143211;5082.872928176788;5075.695458272516;5077.949220507785;5082.974585127051;5087.16061849798;5097.694138351673;4747.881302967454;4769.475357710684;4804.303376010459;4845.685030473008;4851;5230.450802665741;5232.215167724872;5252.422233554306;5258.868528286747;5290.841274761763;4868.050730107666;4929.285253638202;4997.575206107449;5040.546350828443;5058.494150584986;5099.566536844335;5140.871478213075;5141.357330333708;5154.072266991978;5255.579553635776;5113.923291150636;5131.4868513148385;5135.12702840518;5155.845324640267;5193.506616871391;4822.879428014257;5150.330457040635;5156.20078887773;5164.431912489623;5164.638475306692;4235.724677895927;4386.2762644163995;4408.338749187647;4415.646748260131;4492.505824359383;3835.7314987951136;3844.1735026969336;3847.5960024197616;3875.8643447479203;3884.320243957353;4555.043440877386;4563.0873825235085;4565.703229290086;4573.268277075652;4603.930941035887;4995.475475075064;5031.396232452129;5040.59675225981;5067.087924173684;5123.2827404163445;4424.292113261863;4447.221736196153;4457.064016556495;4458.019235768191;4460.3532487789735;4618.67669263155;4622.375979242826;4630;4633.907321853544;4649.4420669519195;4845.466998630078;4854.635902307412;4880.438749543706;4891.095147397743;4929.359183306147;5174.7153906535505;5184.766685499086;5199.974000129975;5210.192420174727;5251.474852514718;4639.536046395334;4814.638902082288;4839.395075615548;4846.103470857903;4864.562189402971;5044.6973181607755;5091.87270318245;5094.0830650481785;5135.537801597725;5137.049645815536;5171.095058364846;5176.456472070378;5186.628691350502;5189.273501709839;5224.52979231871;4844.491328410466;4893.388326459192;5000.549840546347;5015.774290157025;5030.0694371541385;4984.277279794337;5047.621428392812;5068.037072956126;5103.209002604603;5113.53978141969;4927.187014142626;4956.355673762388;5002.399712034579;5008.073506401327;5052.1411360069615;4852.636052296021;4916.557509824133;5077.289179514797;5102.566281866083;5111.693298402108;4929.778159982881;4961.107000739901;4973.701577905337;4984.850454486371;5043.87390315245 16:14:00 INFO - PROCESS | 17720 | |1;ext.html;505.76734702037083;538.1685296217344;552.7927027364736;554.1355485442707;558.4778232352753;36430.27139457209;38319.61680383199;38353.42469862951;38562.614373856326;42186;38721.80639096808;39009.21981560367;39077;39097.21805563887;39135;7640.197779233184;7757.922420775776;7817.530948143101;7840.13758486566;9003.054679258681;9049.864252036225;9249.12133347331;9272.072792720708;9305.441003723963;9311.813763724722;9165.862512062324;9223.985361610217;9244.445333279991;9328.113829186217;9328.720138395825;9221.262299016063;9221.446713197163;9227;9298.62805487783;9337.486438245895;9100.63597456101;9221.30840186982;9258.444493330355;9261.351705380626;9273.07269273074;31751.682483175104;31854.681453185636;32034.359312813845;32128;32308.515372269434;3369.4103531881824;3451.5167876497057;3466.272082862602;3494.8951531454095;3522.577290725104;25035.62446563285;25075.122370716937;25095.61209236748;25200.495990080097;25615.359616009562;3210.7110360067472;3312.519684645713;3313.088900552343;3335.4163021471386;3388.8813891513923;223665;224883;225034;225752;226294;38011.429828552595;39090.21819563593;45829.31256031162;45874.77062614666;46238.76880615576;59253;59337.703311483165;59646.40353596495;59922.40077599255;60481.39518604758;12036;12215.206011609394;12500.49998000088;12505.437255323532;12590.43343049565;9231.07689231085;9233.769155771159;9281.257499400032;9297.07029297065;9306.441613503077;18262.634747305245;18291.359802407118;18597.628047439244;18614;18654.813451865306;6970.721171153198;7007.229204787469;7057.482641232057;7205.387542058879;7393.704251829974;736.9557826530425;757.2200633347661;760.4904713841739;765.7511308824654;766.1916677904863;22840;23867.641985370232;23980;24083.879580601984;24334.26997190087;888.9022207557293;890.4434728294816;900.0774206438322;907.0340087806596;914.5518695838991;12849.164804287691;13222.338883055809;13502.122362046435;13524.18854868711;13564.186148831102;341612;345496;346877;346921;347417;47841.282380764314;47964.28053579199;48372;48398;48445.27332090022;1125.0830573083074;1179.9823002654966;1180.3213152437384;1195.0678470792795;1198.5924785572947;23987.285149514475;24974.500509990063;25340.619890701284;25539.489210216063;26090;39285.8035709831;39356.819295421185;39557.40663889986;39843.203135936536;40008;304032;305044;306730;307035;307892;32939.17652058718;37966.62033379631;38237.808810955765;39357.30925742688;42327;37825.81087094547;38944;39097.6090239094;41140.794296028325;41218.1756364877;34250.65749342475;34844.477332840535;35030.47454288239;35319.29361412809;35980.64019359773;34728.826355868056;34980.12549686278;35151.47272790859;35510.289794203454;35795.64204358027;34693.30613387668;34703;35353.292934140656;36154.276914461036;37967.81016094902;30180;32702.34595308033;32782.1804454881;33097.669023309456;33260.33479330448;23682;24262.514749704555;24364.51270974535;24545;24727.50544989054 16:14:00 INFO - PROCESS | 17720 | |2;jquery.html;380.9371453710138;383.2029378891903;384.060494502761;397.76929380959035;418.6483353982654;12794.936025319883;15738.763918541232;17271.65456690867;17914.372996945094;17916;16680.249388777505;18092.185851636674;18133.90933045333;18140.455786326398;18343.17455714493;5160;5196.636235463519;5270.525652691257;5337.733113344337;5388.272583201277;5171;5260.894782104356;5272.789088436458;5275.788968441258;5276.2085687146855;5546.500814926656;5596.132599447072;5604.243427137346;5620.718964051781;5633.690147041911;5590.86022849428;5604.85987850305;5607.4672906074065;5908.881822363529;6223.96888015557;5520.503154716054;5541.141123125882;5589.692566908819;5591.4688104629995;5596.300462442195;12656.114072014963;17117.657646847118;21116.260930867345;21164.259250926145;21318.360449186548;2503.662005629235;2518.7103483099495;2534.6578211941337;2537.936551586207;2538.2892790018823;9266.629334826599;9369.156775890135;9485.672005919178;9855.802883942353;9969.302148849583;1670.1649175412294;1670.966580668392;1732.731426628862;1776.1474492243794;1855.8236967488067;72583.63708181478;72869.63565182193;73329.26670733331;74609.25390746078;76270.61864690697;8936.8659470108;9195.77010574741;9345.439273643604;9402.717918462457;10986.560537578567;11919.761604767857;12768.872311276955;12833.935830320788;12973.935130324287;12976.870231297755;6392.4246817786625;6448.387403196689;6544.149260596188;6584.8024559263295;6598.7030583623855;6060.666663333471;6075.483583895419;6269.341719119518;6279.309275979638;6299.874002519924;3839.9424008639894;3901.746386484926;3906.648401643866;4028.7179897407486;4171.290880550314;1925.9711104333446;1953.9218431262875;1992.163291417608;2001.4696105532046;2010.7184994100837;773.6595897804948;789.4473868292218;798.3892322373389;806.5362416610473;815.7552734179841;17539.473815785546;17567.560810979572;18177.363792267206;18197.54506137331;18663.16015779266;784.8508783331149;790.4111436979487;794.1938931984041;797.9521228726295;818.2267756969726;9873.40759554429;9972.301938864355;10200.3369780964;10247.282690211614;10247.948760256151;462953;464618;473538;480236;482252;82990;89583;91519;91553.54223228841;92298.53850730704;895.6820328783189;898.2095755734912;930.437085563238;934.7569631895756;937.0254934867661;15197.468088616846;15292.923535382251;15940.840591593935;16108.27512761905;16133.435329763402;38694.61305387023;39399.212015760095;39618.405723914744;39736.2052758949;40066.198676025735;52777.736111319195;53645;54013.72993135009;55164.44835551593;56517;7436.665350059152;7691.807704807425;7735.149133595412;7738.922610774045;8557.48655080697;9352.625894964125;11320.830187547028;11627.825582616437;12347;12958.870411296144;9482.620695172393;9598.168540280494;9834.21326293909;9936.20510359184;10110.8483372748;9861.852072218779;10192.184625230113;10289.639862604772;10315.12321452655;10435.113015393777;9628.518574071408;9720.659776907774;9754.707358779247;9989.500524973868;10185.541650625592;7747;7778.922210778046;7834.294913457816;7910.327622152182;8155;9963.807962248295;10659.680209593724;10703.785924281627;10799.298045627009;11106.222564420414 16:14:00 INFO - PROCESS | 17720 | |3;mootools.html;1204.5181927229112;1215.5198696514876;1220.9084318676098;1223.5105957616947;1244.6639407360012;7050.541714788542;7196.13646362436;7366.558006519599;7423.517471364357;7451.478396512253;7411.814704632387;7608.239176082389;7613.20061393554;7616.31453169216;7645.61771911405;1931.3433432632867;1960.8137226963488;1962.8724132931386;1966.5280332720115;1968.271739456396;1779.1460099152414;1819.7907240667294;1825.2334019711752;1829.4145873320542;1835.779706435223;2002.1090614676475;2014;2021.1006102284489;2021.4643119573325;2029.1071928351478;1903.5241189702574;1952.0825212150266;1956.872803267783;1957.6378370001453;1961.8920959347158;1896.2320260294603;1960.4314748722854;1983.4049785064424;1993.3920154352916;2035.2673037706413;7241.05866237391;7293.562386256842;7317.963410182915;7326.67029983652;7335.376492998102;4433.955660443419;4513.684042117053;4542.931856022163;4561.270196768534;4564.8174073037035;2676.1703871799805;2836.418534200484;2851.928701782472;2903.651561812575;2934.8679309430913;1077.256692881909;1121.4000509727257;1122.9213955023151;1136.8408422820812;1167.8014737494645;7794.41541884361;8122.553259570721;9752;9755.170810481117;9833.31166818323;3724.2365315110337;3857.787821669779;3869.4582758413844;3921.529416469985;3925.666318362914;7959.283664470109;7975.2423519765525;7976.242256985577;7995;8000.319972802377;4446.110777844444;4457.821687132543;4465.240909045405;4469.508354081048;4474.664400170001;3834.6548810606623;3963.4847469828737;3977.423273625308;4153.958460415357;4158.9584104158575;3165.5409965555327;3278.246003419227;3291.1772056985756;3315.651856555075;3324.9168770780925;1029.5881647340973;1042.1454407386022;1051.1065594244983;1055.6674647486018;1061.0556604621893;618.944295013451;645.6836150286326;647.1554621219354;650.6681592387916;656.9310222426672;4616.907661846812;4712.764361781898;4840.588549973221;4842.249451335048;4962.528559786814;496.77645059723267;500.6145268143582;507.45955557331325;512.6667666017119;525.8685328667833;3040.7111324424145;3217.4691175956173;3245.837708114585;3343.4726363686545;3354.6477619849566;8572.742817715478;8833.07252738466;8881.644734210688;8883.244924181388;8888.244499217639;8720;8781.121887811169;8803.207711305886;8823.867641985375;8834.602442890086;6763.763268285685;6844.965775171092;6862.451003919575;6914.204866440323;6925.757598484029;4553.089382123522;4589.2657174851865;4593.379893714306;4655.324977878121;4716.834910778106;7974.16271291506;8041.155678653891;8044.557549334842;8066.516009039477;8130.268275855202;6630.7679231226675;7832.921670783448;8079.192080791874;8130.471519351223;8138;4948.208286674115;5666.631668941505;5830.242068531234;5871.823845284648;5939.46544810969;6780.69486873082;6784.59292442455;6847.075644788089;6850.21222559412;6853.143357080365;6283.214598175228;6391.840203994937;6444.484441244783;6510.3489651034515;6527.640979745969;6263.311035785969;6299.30707622152;6306.400891915444;6352.333005034589;6369.490440764635;5603.243562119062;5666.376698563073;5673.5461163107675;5677.602567820213;5736.082226843685;4114.979425102855;4135.8966025849595;4196.286631272752;4207.684423668176;4314.503832059353;4171.770552619514;4244.893877652959;4265.274903266514;4308.4399028126145;4385.144896745104 16:14:00 INFO - PROCESS | 17720 | |4;prototype.html;333.36826713378133;333.96493368196326;334.64360456114224;356.50267876312535;364.8996525955363;5045.192769156931;5127.282180494729;5133.6663116897425;5150.330457040589;5153.196381266554;4981.651284410093;4991.376077990251;5031.195008798593;5034.5468907798295;5045.419776725677;3702.8150991682674;3857.4926297906445;3867.1878905429894;3892.630200130983;3895.590962948892;3864.3044252034624;3875;3879.573246942834;3880.9805950970203;3907.1404291055924;3845.1540661054664;3848.499695039655;3870.1098747288142;3877.1276462795927;3881.7282790204695;3656.378415669316;3834.482344883433;3860.2279544091152;3867.9613203868025;3874.1864208516245;3374.0889959710985;3374.375740487993;3375.5274261603395;3386.508956201337;3404.3361544498835;4864.635152363551;4869.5130486951375;4876.317315575787;4885.2427873679635;4899.726071221472;4373.737575745466;4376.343548467724;4385.451818522684;4392.253316936129;4405.801738921724;5050.444451110375;5205.7397130143345;5224.242484839677;5247.323864828964;5256.211568264753;3682.5949145593963;3695.593484716679;3695.815209239554;3702.2780557791243;3702.5742039665515;5175;5315.627906046617;5487.64330318528;5495.807646732345;5499;3666.2850744104644;3737.8225858854375;3779.962200377961;3782.829772660237;3789;3810.1046254130256;3827.770333779982;3828.502294701671;3837.597052309523;3856.2646808936092;4388.253996820548;4397.40635014269;4401.493828209733;4421.977890110529;4458.554144585515;5130.948690513047;5180.248863914787;5218.6868787872845;5238.873642166953;5255.237990491358;1281.4681907008674;1295;1296.4749276543016;1305.1320871620267;1305.5964837799213;818.7502811642506;868.9304855611535;874.1258741258741;884.1821315283337;884.6107712606432;4474.149911516802;4477.19410506112;4498;4505.662075344362;4511.1654343946475;5216.9305292414965;5254.868628284247;5271.578273738169;5273.419923808378;5317.73411329432;4261.659067274611;4503.459584849839;4507.6168525675685;4515.71007542829;4550.905384338682;2741.6709994800353;2783.860806959644;2785.2174608250734;2785.3454438207004;2803.3972695870484;4875.390576177978;4897.865366165657;4913.803447862117;4932.1861892788;4936.827211047668;4861.975690121598;5268.552173065255;5316.840494785162;5334.279872217278;5351.973240133774;4860.684055536379;4864.030067822538;4879.414470263591;4886.068358984581;4905.754712264301;5203.297554830201;5205.245239440335;5245.711485868162;5256.369235691742;5300.515855560449;4908.803647854046;5089.8727531812;5157.8452646420665;5231.633785634969;5232;4765.547273009059;4883.487233840538;4883.780229889735;4885.609151267961;4893.21708526634;3671.6695497405362;3746.157114649291;3762.5108735864164;3765.566959799658;3788.204477059738;5372.597055220952;5374.817540141163;5397.217403476396;5399.136138217867;5403.270558474555;4953.315872603188;5196.194589838581;5213.504717051874;5215.895682086261;5225.798066444663;5224.7648855802345;5255.211718242142;5262;5265;5266.235811003204;5165.405978312392;5170.560502357342;5195.7142357170715;5196.946381822185;5203.16749320107;4939.851804445872;5236.659617124875;5298;5304.230886521355;5319.335083114611;5153.845384638467;5155.226715992631;5163;5170.1727723564045;5194.3247377840635 16:14:00 INFO - PROCESS | 17720 | |5;yui.html;606.2088973889074;628.3088602537209;630.8675178212575;635.9364063593638;642.1876326447044;12113.878861211386;12873.742525149491;12888.677783055427;12890.871091289084;13018.153820001693;12151.817722734144;12269.877301227007;12289.631311060682;12297.385130743472;12316.261024338524;771.1517330935953;793.7936136604495;796.1004065406083;818.2063398503471;825.63671984327;812.7358608452247;813.2639960835423;813.3168138763438;813.3818298093462;813.4387272781762;864.3344624639025;865.0095640491634;865.1435079271539;865.35098675993;867.1285358214985;856.8029353248756;856.9700060497853;860.1914200651399;862.1249431826669;862.1981557151846;622.6637615687523;623.3953065526432;623.9625622462667;626.0640342687711;630.4609558827228;13370.264635445046;13611.183329000292;13694;13726.725465490734;13753.312334383341;12718.491260349667;13173.407196676173;13204;13325.944453597274;13494.460221591125;10557.208209384327;10634;10679.78640427187;10856.782864342747;10923.235373523856;3129.937401251962;3195.760317976161;3206.695363940422;3207.7433805295755;3211.389835931189;54130;54784;55047.72476137594;55167.448325516634;57247.427525724626;10070.395776253448;10130;10151.1371533419;10274;10387.68836934893;18524.907375463306;18654.81345186558;18784.81215187858;18809.623807523774;18980.715289270673;6370.394812492806;6383.968080159662;6465.224173099165;6494;6546.235914349269;3482.5995010574106;3569.161247106928;3591.6767490925417;3624.909377265537;3661.395869681526;2955.453241150394;3064.6628870824193;3096.4581198290207;3096.7367773739484;3116.937661246762;540.6188637010898;546.4972225552501;549.8240563019875;557.969311687861;560.1961185698535;573.6414740787009;601.3084952304797;606.7421345927963;613.8496068463255;630.3979699387074;8118.188181181835;8391.748247552583;8405.159484051546;8407.159284071544;8581.742547723577;584.6316820403118;604.4439116013191;612.4426771637789;616.605372561561;621.0621960839076;4024.5170579530645;4134.297169481196;4160.126373461547;4162.313218319004;4272.359146128106;60112.69943650254;60199;62029.37970620326;64739.676301618194;65120.34879651238;45955;48682.26976595354;50714;51140.74429627902;55013;46375.76812115918;46937.29594056092;47427.762861185474;47913.5208647916;47976;8658;9105.726828195166;9119.133682300171;9130.132637399436;9169.816603668023;32019;32915;33122.50316245307;33141.66858331482;34169;38751.418728719655;40928.18143637051;41956.79021604872;42183;42846.78576607097;11145.2198346115;11202.88797112051;11232.584994226281;11242.437878105899;11403.087752979907;11915.344656043653;12394.132410731523;12529.874701253235;12727.172733772275;13181.01142414342;11464.656060318204;11764.941175294069;11812.822807658064;12048.939755301168;12086.818697719365;11194.496247668712;11237.7752444949;11427.371494567878;11461.312321260752;11597.362145082101;10253.9487302566;10894.346339219672;10993.120550356112;11300.378479183724;11305.26515776472;9449.905500945177;9485.336026478357;9881.703548893545;9919.454430006419;10484.895151048391;8436.43312862672;8659.220670139717;9319.114684105;9343.34596578233;9600.903990960001 16:14:00 INFO - PROCESS | 17720 | __end_tp_report 16:14:00 INFO - PROCESS | 17720 | __start_cc_report 16:14:00 INFO - PROCESS | 17720 | _x_x_mozilla_cycle_collect,373 16:14:00 INFO - PROCESS | 17720 | __end_cc_report 16:14:00 INFO - PROCESS | 17720 | __startTimestamp1487204040627__endTimestamp 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | ------- Summary: start ------- 16:14:00 INFO - PROCESS | 17720 | Number of tests: 6 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#0] dojo.html Cycles:165 Average:4757.51 Median:4868.78 stddev:491.74 (10.1%) stddev-sans-first:463.69 16:14:00 INFO - PROCESS | 17720 | Values: 2616.9 2650.7 2667.6 2669.5 2679.5 4603.1 4845.5 4868.8 4896.9 4908.1 4637.0 4813.3 4827.6 4832.0 4915.6 4224.5 4342.8 4395.7 4425.4 4618.6 4551.7 4582.9 4619.2 4619.7 4635.0 4276.0 4564.4 4585.6 4604.2 4639.5 4323.7 4344.4 4381.1 4402.5 4445.7 4281.4 4371.3 4383.5 4437.1 4443.2 4704.7 4740.5 4767.5 4769.3 4820.3 4953.2 4963.6 4970.5 5080.9 5082.9 5075.7 5077.9 5083.0 5087.2 5097.7 4747.9 4769.5 4804.3 4845.7 4851.0 5230.5 5232.2 5252.4 5258.9 5290.8 4868.1 4929.3 4997.6 5040.5 5058.5 5099.6 5140.9 5141.4 5154.1 5255.6 5113.9 5131.5 5135.1 5155.8 5193.5 4822.9 5150.3 5156.2 5164.4 5164.6 4235.7 4386.3 4408.3 4415.6 4492.5 3835.7 3844.2 3847.6 3875.9 3884.3 4555.0 4563.1 4565.7 4573.3 4603.9 4995.5 5031.4 5040.6 5067.1 5123.3 4424.3 4447.2 4457.1 4458.0 4460.4 4618.7 4622.4 4630.0 4633.9 4649.4 4845.5 4854.6 4880.4 4891.1 4929.4 5174.7 5184.8 5200.0 5210.2 5251.5 4639.5 4814.6 4839.4 4846.1 4864.6 5044.7 5091.9 5094.1 5135.5 5137.0 5171.1 5176.5 5186.6 5189.3 5224.5 4844.5 4893.4 5000.5 5015.8 5030.1 4984.3 5047.6 5068.0 5103.2 5113.5 4927.2 4956.4 5002.4 5008.1 5052.1 4852.6 4916.6 5077.3 5102.6 5111.7 4929.8 4961.1 4973.7 4984.9 5043.9 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#1] ext.html Cycles:180 Average:44662.26 Median:37128.04 stddev:77851.99 (209.7%) stddev-sans-first:77999.79 16:14:00 INFO - PROCESS | 17720 | Values: 505.8 538.2 552.8 554.1 558.5 36430.3 38319.6 38353.4 38562.6 42186.0 38721.8 39009.2 39077.0 39097.2 39135.0 7640.2 7757.9 7817.5 7840.1 9003.1 9049.9 9249.1 9272.1 9305.4 9311.8 9165.9 9224.0 9244.4 9328.1 9328.7 9221.3 9221.4 9227.0 9298.6 9337.5 9100.6 9221.3 9258.4 9261.4 9273.1 31751.7 31854.7 32034.4 32128.0 32308.5 3369.4 3451.5 3466.3 3494.9 3522.6 25035.6 25075.1 25095.6 25200.5 25615.4 3210.7 3312.5 3313.1 3335.4 3388.9 223665.0 224883.0 225034.0 225752.0 226294.0 38011.4 39090.2 45829.3 45874.8 46238.8 59253.0 59337.7 59646.4 59922.4 60481.4 12036.0 12215.2 12500.5 12505.4 12590.4 9231.1 9233.8 9281.3 9297.1 9306.4 18262.6 18291.4 18597.6 18614.0 18654.8 6970.7 7007.2 7057.5 7205.4 7393.7 737.0 757.2 760.5 765.8 766.2 22840.0 23867.6 23980.0 24083.9 24334.3 888.9 890.4 900.1 907.0 914.6 12849.2 13222.3 13502.1 13524.2 13564.2 341612.0 345496.0 346877.0 346921.0 347417.0 47841.3 47964.3 48372.0 48398.0 48445.3 1125.1 1180.0 1180.3 1195.1 1198.6 23987.3 24974.5 25340.6 25539.5 26090.0 39285.8 39356.8 39557.4 39843.2 40008.0 304032.0 305044.0 306730.0 307035.0 307892.0 32939.2 37966.6 38237.8 39357.3 42327.0 37825.8 38944.0 39097.6 41140.8 41218.2 34250.7 34844.5 35030.5 35319.3 35980.6 34728.8 34980.1 35151.5 35510.3 35795.6 34693.3 34703.0 35353.3 36154.3 37967.8 30180.0 32702.3 32782.2 33097.7 33260.3 23682.0 24262.5 24364.5 24545.0 24727.5 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#2] jquery.html Cycles:180 Average:27217.68 Median:29026.97 stddev:77996.99 (268.7%) stddev-sans-first:78189.76 16:14:00 INFO - PROCESS | 17720 | Values: 380.9 383.2 384.1 397.8 418.6 12794.9 15738.8 17271.7 17914.4 17916.0 16680.2 18092.2 18133.9 18140.5 18343.2 5160.0 5196.6 5270.5 5337.7 5388.3 5171.0 5260.9 5272.8 5275.8 5276.2 5546.5 5596.1 5604.2 5620.7 5633.7 5590.9 5604.9 5607.5 5908.9 6224.0 5520.5 5541.1 5589.7 5591.5 5596.3 12656.1 17117.7 21116.3 21164.3 21318.4 2503.7 2518.7 2534.7 2537.9 2538.3 9266.6 9369.2 9485.7 9855.8 9969.3 1670.2 1671.0 1732.7 1776.1 1855.8 72583.6 72869.6 73329.3 74609.3 76270.6 8936.9 9195.8 9345.4 9402.7 10986.6 11919.8 12768.9 12833.9 12973.9 12976.9 6392.4 6448.4 6544.1 6584.8 6598.7 6060.7 6075.5 6269.3 6279.3 6299.9 3839.9 3901.7 3906.6 4028.7 4171.3 1926.0 1953.9 1992.2 2001.5 2010.7 773.7 789.4 798.4 806.5 815.8 17539.5 17567.6 18177.4 18197.5 18663.2 784.9 790.4 794.2 798.0 818.2 9873.4 9972.3 10200.3 10247.3 10247.9 462953.0 464618.0 473538.0 480236.0 482252.0 82990.0 89583.0 91519.0 91553.5 92298.5 895.7 898.2 930.4 934.8 937.0 15197.5 15292.9 15940.8 16108.3 16133.4 38694.6 39399.2 39618.4 39736.2 40066.2 52777.7 53645.0 54013.7 55164.4 56517.0 7436.7 7691.8 7735.1 7738.9 8557.5 9352.6 11320.8 11627.8 12347.0 12958.9 9482.6 9598.2 9834.2 9936.2 10110.8 9861.9 10192.2 10289.6 10315.1 10435.1 9628.5 9720.7 9754.7 9989.5 10185.5 7747.0 7778.9 7834.3 7910.3 8155.0 9963.8 10659.7 10703.8 10799.3 11106.2 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#3] mootools.html Cycles:180 Average:4687.62 Median:4714.80 stddev:2607.66 (55.3%) stddev-sans-first:2601.84 16:14:00 INFO - PROCESS | 17720 | Values: 1204.5 1215.5 1220.9 1223.5 1244.7 7050.5 7196.1 7366.6 7423.5 7451.5 7411.8 7608.2 7613.2 7616.3 7645.6 1931.3 1960.8 1962.9 1966.5 1968.3 1779.1 1819.8 1825.2 1829.4 1835.8 2002.1 2014.0 2021.1 2021.5 2029.1 1903.5 1952.1 1956.9 1957.6 1961.9 1896.2 1960.4 1983.4 1993.4 2035.3 7241.1 7293.6 7318.0 7326.7 7335.4 4434.0 4513.7 4542.9 4561.3 4564.8 2676.2 2836.4 2851.9 2903.7 2934.9 1077.3 1121.4 1122.9 1136.8 1167.8 7794.4 8122.6 9752.0 9755.2 9833.3 3724.2 3857.8 3869.5 3921.5 3925.7 7959.3 7975.2 7976.2 7995.0 8000.3 4446.1 4457.8 4465.2 4469.5 4474.7 3834.7 3963.5 3977.4 4154.0 4159.0 3165.5 3278.2 3291.2 3315.7 3324.9 1029.6 1042.1 1051.1 1055.7 1061.1 618.9 645.7 647.2 650.7 656.9 4616.9 4712.8 4840.6 4842.2 4962.5 496.8 500.6 507.5 512.7 525.9 3040.7 3217.5 3245.8 3343.5 3354.6 8572.7 8833.1 8881.6 8883.2 8888.2 8720.0 8781.1 8803.2 8823.9 8834.6 6763.8 6845.0 6862.5 6914.2 6925.8 4553.1 4589.3 4593.4 4655.3 4716.8 7974.2 8041.2 8044.6 8066.5 8130.3 6630.8 7832.9 8079.2 8130.5 8138.0 4948.2 5666.6 5830.2 5871.8 5939.5 6780.7 6784.6 6847.1 6850.2 6853.1 6283.2 6391.8 6444.5 6510.3 6527.6 6263.3 6299.3 6306.4 6352.3 6369.5 5603.2 5666.4 5673.5 5677.6 5736.1 4115.0 4135.9 4196.3 4207.7 4314.5 4171.8 4244.9 4265.3 4308.4 4385.1 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#4] prototype.html Cycles:180 Average:4293.62 Median:4884.51 stddev:1248.30 (25.6%) stddev-sans-first:1215.90 16:14:00 INFO - PROCESS | 17720 | Values: 333.4 334.0 334.6 356.5 364.9 5045.2 5127.3 5133.7 5150.3 5153.2 4981.7 4991.4 5031.2 5034.5 5045.4 3702.8 3857.5 3867.2 3892.6 3895.6 3864.3 3875.0 3879.6 3881.0 3907.1 3845.2 3848.5 3870.1 3877.1 3881.7 3656.4 3834.5 3860.2 3868.0 3874.2 3374.1 3374.4 3375.5 3386.5 3404.3 4864.6 4869.5 4876.3 4885.2 4899.7 4373.7 4376.3 4385.5 4392.3 4405.8 5050.4 5205.7 5224.2 5247.3 5256.2 3682.6 3695.6 3695.8 3702.3 3702.6 5175.0 5315.6 5487.6 5495.8 5499.0 3666.3 3737.8 3780.0 3782.8 3789.0 3810.1 3827.8 3828.5 3837.6 3856.3 4388.3 4397.4 4401.5 4422.0 4458.6 5130.9 5180.2 5218.7 5238.9 5255.2 1281.5 1295.0 1296.5 1305.1 1305.6 818.8 868.9 874.1 884.2 884.6 4474.1 4477.2 4498.0 4505.7 4511.2 5216.9 5254.9 5271.6 5273.4 5317.7 4261.7 4503.5 4507.6 4515.7 4550.9 2741.7 2783.9 2785.2 2785.3 2803.4 4875.4 4897.9 4913.8 4932.2 4936.8 4862.0 5268.6 5316.8 5334.3 5352.0 4860.7 4864.0 4879.4 4886.1 4905.8 5203.3 5205.2 5245.7 5256.4 5300.5 4908.8 5089.9 5157.8 5231.6 5232.0 4765.5 4883.5 4883.8 4885.6 4893.2 3671.7 3746.2 3762.5 3765.6 3788.2 5372.6 5374.8 5397.2 5399.1 5403.3 4953.3 5196.2 5213.5 5215.9 5225.8 5224.8 5255.2 5262.0 5265.0 5266.2 5165.4 5170.6 5195.7 5196.9 5203.2 4939.9 5236.7 5298.0 5304.2 5319.3 5153.8 5155.2 5163.0 5170.2 5194.3 16:14:00 INFO - PROCESS | 17720 | 16:14:00 INFO - PROCESS | 17720 | [#5] yui.html Cycles:180 Average:13954.69 Median:44400.89 stddev:16611.47 (37.4%) stddev-sans-first:16627.83 16:14:00 INFO - PROCESS | 17720 | Values: 606.2 628.3 630.9 635.9 642.2 12113.9 12873.7 12888.7 12890.9 13018.2 12151.8 12269.9 12289.6 12297.4 12316.3 771.2 793.8 796.1 818.2 825.6 812.7 813.3 813.3 813.4 813.4 864.3 865.0 865.1 865.4 867.1 856.8 857.0 860.2 862.1 862.2 622.7 623.4 624.0 626.1 630.5 13370.3 13611.2 13694.0 13726.7 13753.3 12718.5 13173.4 13204.0 13325.9 13494.5 10557.2 10634.0 10679.8 10856.8 10923.2 3129.9 3195.8 3206.7 3207.7 3211.4 54130.0 54784.0 55047.7 55167.4 57247.4 10070.4 10130.0 10151.1 10274.0 10387.7 18524.9 18654.8 18784.8 18809.6 18980.7 6370.4 6384.0 6465.2 6494.0 6546.2 3482.6 3569.2 3591.7 3624.9 3661.4 2955.5 3064.7 3096.5 3096.7 3116.9 540.6 546.5 549.8 558.0 560.2 573.6 601.3 606.7 613.8 630.4 8118.2 8391.7 8405.2 8407.2 8581.7 584.6 604.4 612.4 616.6 621.1 4024.5 4134.3 4160.1 4162.3 4272.4 60112.7 60199.0 62029.4 64739.7 65120.3 45955.0 48682.3 50714.0 51140.7 55013.0 46375.8 46937.3 47427.8 47913.5 47976.0 8658.0 9105.7 9119.1 9130.1 9169.8 32019.0 32915.0 33122.5 33141.7 34169.0 38751.4 40928.2 41956.8 42183.0 42846.8 11145.2 11202.9 11232.6 11242.4 11403.1 11915.3 12394.1 12529.9 12727.2 13181.0 11464.7 11764.9 11812.8 12048.9 12086.8 11194.5 11237.8 11427.4 11461.3 11597.4 10253.9 10894.3 10993.1 11300.4 11305.3 9449.9 9485.3 9881.7 9919.5 10484.9 8436.4 8659.2 9319.1 9343.3 9600.9 16:14:00 INFO - PROCESS | 17720 | -------- Summary: end -------- 16:14:00 INFO - PROCESS | 17720 | 16:14:01 INFO - TEST-INFO | 17720: exit 0 16:14:01 INFO - TEST-OK | dromaeo_css | took 1120314ms 16:14:01 INFO - TEST-START | kraken 16:14:01 INFO - operating with platform_type : linux_ 16:14:01 INFO - Initialising browser for kraken test... 16:14:01 INFO - TEST-INFO | started process 18073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplctILs/profile http://localhost:34793/getInfo.html) 16:14:04 INFO - PROCESS | 18073 | Iterating over profile /tmp/tmplctILs/profile 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/places.sqlite-shm = Wed Feb 15 2017 16:14:02 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/crashes = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/minidumps = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/extensions.json = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/sessionCheckpoints.json = Wed Feb 15 2017 16:14:05 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/key3.db = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/permissions.sqlite = Wed Feb 15 2017 16:14:02 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/user.js = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/.parentlock = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/cert8.db = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/compatibility.ini = Wed Feb 15 2017 16:14:02 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/places.sqlite = Wed Feb 15 2017 16:14:05 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/startupCache = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/pluginreg.dat = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/directoryLinks.json = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/prefs.js = Wed Feb 15 2017 15:54:40 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/thumbnails = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/addons.json = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/places.sqlite-wal = Wed Feb 15 2017 16:14:05 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | Stat failure 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/safebrowsing = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | __metrics Screen width/height:1600/1200 16:14:05 INFO - PROCESS | 18073 | colorDepth:24 16:14:05 INFO - PROCESS | 18073 | Browser inner width/height: 1024/697 16:14:05 INFO - PROCESS | 18073 | __metrics 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/mimeTypes.rdf = Wed Feb 15 2017 16:14:04 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/secmod.db = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/extensions.ini = Wed Feb 15 2017 16:14:02 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/cache2 = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/localstore.rdf = Wed Feb 15 2017 15:54:40 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/content-prefs.sqlite = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/frequencyCap.json = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/gmp = Wed Feb 15 2017 16:14:02 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/extensions = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/bookmarkbackups = Wed Feb 15 2017 16:14:03 GMT-0800 (PST) 16:14:05 INFO - PROCESS | 18073 | No birth date. Using mtime. 16:14:05 INFO - PROCESS | 18073 | Using date: /tmp/tmplctILs/profile/blocklist.xml = Wed Feb 15 2017 16:14:01 GMT-0800 (PST) 16:14:06 ERROR - PROCESS | 18073 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 16:14:07 INFO - TEST-INFO | 18073: exit 0 16:14:07 INFO - Browser initialized. 16:14:07 INFO - Running cycle 1/1 for kraken test... 16:14:07 INFO - TEST-INFO | started process 18191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplctILs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:19 INFO - PROCESS | 18191 | RSS: Main: 159965184 16:14:19 INFO - PROCESS | 18191 | 16:14:22 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34793/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:23 INFO - PROCESS | 18191 | RSS: Main: 166260736 16:14:23 INFO - PROCESS | 18191 | 16:14:26 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34793/tests/kraken/driver.html?testName=audio-dft) 16:14:26 INFO - PROCESS | 18191 | RSS: Main: 165548032 16:14:26 INFO - PROCESS | 18191 | 16:14:29 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34793/tests/kraken/driver.html?testName=audio-fft) 16:14:30 INFO - PROCESS | 18191 | RSS: Main: 162631680 16:14:30 INFO - PROCESS | 18191 | 16:14:32 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34793/tests/kraken/driver.html?testName=audio-oscillator) 16:14:33 INFO - PROCESS | 18191 | RSS: Main: 163631104 16:14:33 INFO - PROCESS | 18191 | 16:14:35 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34793/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:14:35 INFO - PROCESS | 18191 | RSS: Main: 163766272 16:14:35 INFO - PROCESS | 18191 | 16:14:39 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34793/tests/kraken/driver.html?testName=imaging-darkroom) 16:14:39 INFO - PROCESS | 18191 | RSS: Main: 168353792 16:14:39 INFO - PROCESS | 18191 | 16:14:43 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34793/tests/kraken/driver.html?testName=imaging-desaturate) 16:14:43 INFO - PROCESS | 18191 | RSS: Main: 165343232 16:14:43 INFO - PROCESS | 18191 | 16:14:46 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34793/tests/kraken/driver.html?testName=json-parse-financial) 16:14:47 INFO - PROCESS | 18191 | RSS: Main: 165998592 16:14:47 INFO - PROCESS | 18191 | 16:14:49 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34793/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:49 INFO - PROCESS | 18191 | RSS: Main: 166309888 16:14:49 INFO - PROCESS | 18191 | 16:14:51 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:52 INFO - PROCESS | 18191 | RSS: Main: 166305792 16:14:52 INFO - PROCESS | 18191 | 16:14:54 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:55 INFO - PROCESS | 18191 | RSS: Main: 166510592 16:14:55 INFO - PROCESS | 18191 | 16:14:58 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:58 INFO - PROCESS | 18191 | RSS: Main: 166780928 16:14:58 INFO - PROCESS | 18191 | 16:15:02 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:02 INFO - PROCESS | 18191 | RSS: Main: 166588416 16:15:02 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | Cycle 1(1): loaded http://localhost:34793/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34793/tests/kraken/driver.html?testName=ai-astar) 16:15:04 INFO - PROCESS | 18191 | RSS: Main: 168460288 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | __start_tp_report 16:15:04 INFO - PROCESS | 18191 | _x_x_mozilla_page_load 16:15:04 INFO - PROCESS | 18191 | _x_x_mozilla_page_load_details 16:15:04 INFO - PROCESS | 18191 | |i|pagename|runs| 16:15:04 INFO - PROCESS | 18191 | |0;ai-astar;108;115;115;117;117;116;117;114;116;118 16:15:04 INFO - PROCESS | 18191 | |1;audio-beat-detection;153;128;133;170;128;126;131;131;150;132 16:15:04 INFO - PROCESS | 18191 | |2;audio-dft;166;165;167;172;172;169;163;164;164;163 16:15:04 INFO - PROCESS | 18191 | |3;audio-fft;77;78;78;111;78;79;77;79;96;76 16:15:04 INFO - PROCESS | 18191 | |4;audio-oscillator;85;86;120;85;85;84;100;85;84;105 16:15:04 INFO - PROCESS | 18191 | |5;imaging-gaussian-blur;114;115;115;114;114;115;114;114;118;113 16:15:04 INFO - PROCESS | 18191 | |6;imaging-darkroom;151;152;150;152;151;151;150;150;151;152 16:15:04 INFO - PROCESS | 18191 | |7;imaging-desaturate;96;97;96;100;96;95;96;97;96;96 16:15:04 INFO - PROCESS | 18191 | |8;json-parse-financial;70;66;71;67;68;68;68;68;68;67 16:15:04 INFO - PROCESS | 18191 | |9;json-stringify-tinderbox;66;67;66;66;66;65;65;66;65;66 16:15:04 INFO - PROCESS | 18191 | |10;stanford-crypto-aes;89;89;92;87;93;89;88;90;91;88 16:15:04 INFO - PROCESS | 18191 | |11;stanford-crypto-ccm;156;152;140;150;140;143;140;143;183;148 16:15:04 INFO - PROCESS | 18191 | |12;stanford-crypto-pbkdf2;184;183;195;182;189;185;186;185;192;184 16:15:04 INFO - PROCESS | 18191 | |13;stanford-crypto-sha256-iterative;80;73;72;72;76;71;71;71;71;72 16:15:04 INFO - PROCESS | 18191 | __end_tp_report 16:15:04 INFO - PROCESS | 18191 | __start_cc_report 16:15:04 INFO - PROCESS | 18191 | _x_x_mozilla_cycle_collect,873 16:15:04 INFO - PROCESS | 18191 | __end_cc_report 16:15:04 INFO - PROCESS | 18191 | __startTimestamp1487204104834__endTimestamp 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | ------- Summary: start ------- 16:15:04 INFO - PROCESS | 18191 | Number of tests: 14 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.50 stddev:2.83 (2.4%) stddev-sans-first:1.27 16:15:04 INFO - PROCESS | 18191 | Values: 108.0 115.0 115.0 117.0 117.0 116.0 117.0 114.0 116.0 118.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:132.50 stddev:14.51 (11.0%) stddev-sans-first:14.37 16:15:04 INFO - PROCESS | 18191 | Values: 153.0 128.0 133.0 170.0 128.0 126.0 131.0 131.0 150.0 132.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#2] audio-dft Cycles:10 Average:166.50 Median:166.50 stddev:3.44 (2.1%) stddev-sans-first:3.64 16:15:04 INFO - PROCESS | 18191 | Values: 166.0 165.0 167.0 172.0 172.0 169.0 163.0 164.0 164.0 163.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#3] audio-fft Cycles:10 Average:82.90 Median:78.00 stddev:11.45 (14.7%) stddev-sans-first:11.95 16:15:04 INFO - PROCESS | 18191 | Values: 77.0 78.0 78.0 111.0 78.0 79.0 77.0 79.0 96.0 76.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:85.00 stddev:12.37 (14.6%) stddev-sans-first:12.86 16:15:04 INFO - PROCESS | 18191 | Values: 85.0 86.0 120.0 85.0 85.0 84.0 100.0 85.0 84.0 105.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:1.35 (1.2%) stddev-sans-first:1.41 16:15:04 INFO - PROCESS | 18191 | Values: 114.0 115.0 115.0 114.0 114.0 115.0 114.0 114.0 118.0 113.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 16:15:04 INFO - PROCESS | 18191 | Values: 151.0 152.0 150.0 152.0 151.0 151.0 150.0 150.0 151.0 152.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:96.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 16:15:04 INFO - PROCESS | 18191 | Values: 96.0 97.0 96.0 100.0 96.0 95.0 96.0 97.0 96.0 96.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.36 16:15:04 INFO - PROCESS | 18191 | Values: 70.0 66.0 71.0 67.0 68.0 68.0 68.0 68.0 68.0 67.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 16:15:04 INFO - PROCESS | 18191 | Values: 66.0 67.0 66.0 66.0 66.0 65.0 65.0 66.0 65.0 66.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:89.50 stddev:1.90 (2.1%) stddev-sans-first:2.00 16:15:04 INFO - PROCESS | 18191 | Values: 89.0 89.0 92.0 87.0 93.0 89.0 88.0 90.0 91.0 88.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:149.00 stddev:13.03 (8.7%) stddev-sans-first:13.61 16:15:04 INFO - PROCESS | 18191 | Values: 156.0 152.0 140.0 150.0 140.0 143.0 140.0 143.0 183.0 148.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:185.50 stddev:4.20 (2.3%) stddev-sans-first:4.35 16:15:04 INFO - PROCESS | 18191 | Values: 184.0 183.0 195.0 182.0 189.0 185.0 186.0 185.0 192.0 184.0 16:15:04 INFO - PROCESS | 18191 | 16:15:04 INFO - PROCESS | 18191 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.00 stddev:2.92 (4.1%) stddev-sans-first:1.62 16:15:04 INFO - PROCESS | 18191 | Values: 80.0 73.0 72.0 72.0 76.0 71.0 71.0 71.0 71.0 72.0 16:15:04 INFO - PROCESS | 18191 | -------- Summary: end -------- 16:15:04 INFO - PROCESS | 18191 | 16:15:05 INFO - TEST-INFO | 18191: exit 0 16:15:05 INFO - TEST-OK | kraken | took 64475ms 16:15:05 INFO - SUITE-END | took 1184s 16:15:06 INFO - Completed test suite (00:19:45) 16:15:06 INFO - javascript benchmark 16:15:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6277.994514586906, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2616.947661046779, 2650.655414796076, 2667.5865240887656, 2669.5328317544436, 2679.5310820606387, 4603.125406172825, 4845.539673730995, 4868.780904859276, 4896.926546101807, 4908.141075311815, 4637.003044345465, 4813.2780082987565, 4827.56551910328, 4832.0, 4915.6067514598835, 4224.5353011168745, 4342.826286948518, 4395.7362558246605, 4425.446819147606, 4618.630509559244, 4551.704139230956, 4582.885427864297, 4619.237825758746, 4619.74591397473, 4635.003474253035, 4276.0, 4564.383808185886, 4585.6102231310215, 4604.194266003469, 4639.489656137839, 4323.6757243206885, 4344.391785150082, 4381.145676593065, 4402.471703395613, 4445.666575006888, 4281.400603915455, 4371.256886329332, 4383.495897971742, 4437.090396468716, 4443.2224360736745, 4704.6941948773565, 4740.525947405266, 4767.451743049524, 4769.332293478916, 4820.325154478376, 4953.182724850395, 4963.578095861856, 4970.453250142481, 5080.923786143211, 5082.872928176788, 5075.695458272516, 5077.949220507785, 5082.974585127051, 5087.16061849798, 5097.694138351673, 4747.881302967454, 4769.475357710684, 4804.303376010459, 4845.685030473008, 4851.0, 5230.450802665741, 5232.215167724872, 5252.422233554306, 5258.868528286747, 5290.841274761763, 4868.050730107666, 4929.285253638202, 4997.575206107449, 5040.546350828443, 5058.494150584986, 5099.566536844335, 5140.871478213075, 5141.357330333708, 5154.072266991978, 5255.579553635776, 5113.923291150636, 5131.4868513148385, 5135.12702840518, 5155.845324640267, 5193.506616871391, 4822.879428014257, 5150.330457040635, 5156.20078887773, 5164.431912489623, 5164.638475306692, 4235.724677895927, 4386.2762644163995, 4408.338749187647, 4415.646748260131, 4492.505824359383, 3835.7314987951136, 3844.1735026969336, 3847.5960024197616, 3875.8643447479203, 3884.320243957353, 4555.043440877386, 4563.0873825235085, 4565.703229290086, 4573.268277075652, 4603.930941035887, 4995.475475075064, 5031.396232452129, 5040.59675225981, 5067.087924173684, 5123.2827404163445, 4424.292113261863, 4447.221736196153, 4457.064016556495, 4458.019235768191, 4460.3532487789735, 4618.67669263155, 4622.375979242826, 4630.0, 4633.907321853544, 4649.4420669519195, 4845.466998630078, 4854.635902307412, 4880.438749543706, 4891.095147397743, 4929.359183306147, 5174.7153906535505, 5184.766685499086, 5199.974000129975, 5210.192420174727, 5251.474852514718, 4639.536046395334, 4814.638902082288, 4839.395075615548, 4846.103470857903, 4864.562189402971, 5044.6973181607755, 5091.87270318245, 5094.0830650481785, 5135.537801597725, 5137.049645815536, 5171.095058364846, 5176.456472070378, 5186.628691350502, 5189.273501709839, 5224.52979231871, 4844.491328410466, 4893.388326459192, 5000.549840546347, 5015.774290157025, 5030.0694371541385, 4984.277279794337, 5047.621428392812, 5068.037072956126, 5103.209002604603, 5113.53978141969, 4927.187014142626, 4956.355673762388, 5002.399712034579, 5008.073506401327, 5052.1411360069615, 4852.636052296021, 4916.557509824133, 5077.289179514797, 5102.566281866083, 5111.693298402108, 4929.778159982881, 4961.107000739901, 4973.701577905337, 4984.850454486371, 5043.87390315245], "value": 4726.291965509129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.76734702037083, 538.1685296217344, 552.7927027364736, 554.1355485442707, 558.4778232352753, 36430.27139457209, 38319.61680383199, 38353.42469862951, 38562.614373856326, 42186.0, 38721.80639096808, 39009.21981560367, 39077.0, 39097.21805563887, 39135.0, 7640.197779233184, 7757.922420775776, 7817.530948143101, 7840.13758486566, 9003.054679258681, 9049.864252036225, 9249.12133347331, 9272.072792720708, 9305.441003723963, 9311.813763724722, 9165.862512062324, 9223.985361610217, 9244.445333279991, 9328.113829186217, 9328.720138395825, 9221.262299016063, 9221.446713197163, 9227.0, 9298.62805487783, 9337.486438245895, 9100.63597456101, 9221.30840186982, 9258.444493330355, 9261.351705380626, 9273.07269273074, 31751.682483175104, 31854.681453185636, 32034.359312813845, 32128.0, 32308.515372269434, 3369.4103531881824, 3451.5167876497057, 3466.272082862602, 3494.8951531454095, 3522.577290725104, 25035.62446563285, 25075.122370716937, 25095.61209236748, 25200.495990080097, 25615.359616009562, 3210.7110360067472, 3312.519684645713, 3313.088900552343, 3335.4163021471386, 3388.8813891513923, 223665.0, 224883.0, 225034.0, 225752.0, 226294.0, 38011.429828552595, 39090.21819563593, 45829.31256031162, 45874.77062614666, 46238.76880615576, 59253.0, 59337.703311483165, 59646.40353596495, 59922.40077599255, 60481.39518604758, 12036.0, 12215.206011609394, 12500.49998000088, 12505.437255323532, 12590.43343049565, 9231.07689231085, 9233.769155771159, 9281.257499400032, 9297.07029297065, 9306.441613503077, 18262.634747305245, 18291.359802407118, 18597.628047439244, 18614.0, 18654.813451865306, 6970.721171153198, 7007.229204787469, 7057.482641232057, 7205.387542058879, 7393.704251829974, 736.9557826530425, 757.2200633347661, 760.4904713841739, 765.7511308824654, 766.1916677904863, 22840.0, 23867.641985370232, 23980.0, 24083.879580601984, 24334.26997190087, 888.9022207557293, 890.4434728294816, 900.0774206438322, 907.0340087806596, 914.5518695838991, 12849.164804287691, 13222.338883055809, 13502.122362046435, 13524.18854868711, 13564.186148831102, 341612.0, 345496.0, 346877.0, 346921.0, 347417.0, 47841.282380764314, 47964.28053579199, 48372.0, 48398.0, 48445.27332090022, 1125.0830573083074, 1179.9823002654966, 1180.3213152437384, 1195.0678470792795, 1198.5924785572947, 23987.285149514475, 24974.500509990063, 25340.619890701284, 25539.489210216063, 26090.0, 39285.8035709831, 39356.819295421185, 39557.40663889986, 39843.203135936536, 40008.0, 304032.0, 305044.0, 306730.0, 307035.0, 307892.0, 32939.17652058718, 37966.62033379631, 38237.808810955765, 39357.30925742688, 42327.0, 37825.81087094547, 38944.0, 39097.6090239094, 41140.794296028325, 41218.1756364877, 34250.65749342475, 34844.477332840535, 35030.47454288239, 35319.29361412809, 35980.64019359773, 34728.826355868056, 34980.12549686278, 35151.47272790859, 35510.289794203454, 35795.64204358027, 34693.30613387668, 34703.0, 35353.292934140656, 36154.276914461036, 37967.81016094902, 30180.0, 32702.34595308033, 32782.1804454881, 33097.669023309456, 33260.33479330448, 23682.0, 24262.514749704555, 24364.51270974535, 24545.0, 24727.50544989054], "value": 17140.719255875145, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.9371453710138, 383.2029378891903, 384.060494502761, 397.76929380959035, 418.6483353982654, 12794.936025319883, 15738.763918541232, 17271.65456690867, 17914.372996945094, 17916.0, 16680.249388777505, 18092.185851636674, 18133.90933045333, 18140.455786326398, 18343.17455714493, 5160.0, 5196.636235463519, 5270.525652691257, 5337.733113344337, 5388.272583201277, 5171.0, 5260.894782104356, 5272.789088436458, 5275.788968441258, 5276.2085687146855, 5546.500814926656, 5596.132599447072, 5604.243427137346, 5620.718964051781, 5633.690147041911, 5590.86022849428, 5604.85987850305, 5607.4672906074065, 5908.881822363529, 6223.96888015557, 5520.503154716054, 5541.141123125882, 5589.692566908819, 5591.4688104629995, 5596.300462442195, 12656.114072014963, 17117.657646847118, 21116.260930867345, 21164.259250926145, 21318.360449186548, 2503.662005629235, 2518.7103483099495, 2534.6578211941337, 2537.936551586207, 2538.2892790018823, 9266.629334826599, 9369.156775890135, 9485.672005919178, 9855.802883942353, 9969.302148849583, 1670.1649175412294, 1670.966580668392, 1732.731426628862, 1776.1474492243794, 1855.8236967488067, 72583.63708181478, 72869.63565182193, 73329.26670733331, 74609.25390746078, 76270.61864690697, 8936.8659470108, 9195.77010574741, 9345.439273643604, 9402.717918462457, 10986.560537578567, 11919.761604767857, 12768.872311276955, 12833.935830320788, 12973.935130324287, 12976.870231297755, 6392.4246817786625, 6448.387403196689, 6544.149260596188, 6584.8024559263295, 6598.7030583623855, 6060.666663333471, 6075.483583895419, 6269.341719119518, 6279.309275979638, 6299.874002519924, 3839.9424008639894, 3901.746386484926, 3906.648401643866, 4028.7179897407486, 4171.290880550314, 1925.9711104333446, 1953.9218431262875, 1992.163291417608, 2001.4696105532046, 2010.7184994100837, 773.6595897804948, 789.4473868292218, 798.3892322373389, 806.5362416610473, 815.7552734179841, 17539.473815785546, 17567.560810979572, 18177.363792267206, 18197.54506137331, 18663.16015779266, 784.8508783331149, 790.4111436979487, 794.1938931984041, 797.9521228726295, 818.2267756969726, 9873.40759554429, 9972.301938864355, 10200.3369780964, 10247.282690211614, 10247.948760256151, 462953.0, 464618.0, 473538.0, 480236.0, 482252.0, 82990.0, 89583.0, 91519.0, 91553.54223228841, 92298.53850730704, 895.6820328783189, 898.2095755734912, 930.437085563238, 934.7569631895756, 937.0254934867661, 15197.468088616846, 15292.923535382251, 15940.840591593935, 16108.27512761905, 16133.435329763402, 38694.61305387023, 39399.212015760095, 39618.405723914744, 39736.2052758949, 40066.198676025735, 52777.736111319195, 53645.0, 54013.72993135009, 55164.44835551593, 56517.0, 7436.665350059152, 7691.807704807425, 7735.149133595412, 7738.922610774045, 8557.48655080697, 9352.625894964125, 11320.830187547028, 11627.825582616437, 12347.0, 12958.870411296144, 9482.620695172393, 9598.168540280494, 9834.21326293909, 9936.20510359184, 10110.8483372748, 9861.852072218779, 10192.184625230113, 10289.639862604772, 10315.12321452655, 10435.113015393777, 9628.518574071408, 9720.659776907774, 9754.707358779247, 9989.500524973868, 10185.541650625592, 7747.0, 7778.922210778046, 7834.294913457816, 7910.327622152182, 8155.0, 9963.807962248295, 10659.680209593724, 10703.785924281627, 10799.298045627009, 11106.222564420414], "value": 8343.13815983082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1204.5181927229112, 1215.5198696514876, 1220.9084318676098, 1223.5105957616947, 1244.6639407360012, 7050.541714788542, 7196.13646362436, 7366.558006519599, 7423.517471364357, 7451.478396512253, 7411.814704632387, 7608.239176082389, 7613.20061393554, 7616.31453169216, 7645.61771911405, 1931.3433432632867, 1960.8137226963488, 1962.8724132931386, 1966.5280332720115, 1968.271739456396, 1779.1460099152414, 1819.7907240667294, 1825.2334019711752, 1829.4145873320542, 1835.779706435223, 2002.1090614676475, 2014.0, 2021.1006102284489, 2021.4643119573325, 2029.1071928351478, 1903.5241189702574, 1952.0825212150266, 1956.872803267783, 1957.6378370001453, 1961.8920959347158, 1896.2320260294603, 1960.4314748722854, 1983.4049785064424, 1993.3920154352916, 2035.2673037706413, 7241.05866237391, 7293.562386256842, 7317.963410182915, 7326.67029983652, 7335.376492998102, 4433.955660443419, 4513.684042117053, 4542.931856022163, 4561.270196768534, 4564.8174073037035, 2676.1703871799805, 2836.418534200484, 2851.928701782472, 2903.651561812575, 2934.8679309430913, 1077.256692881909, 1121.4000509727257, 1122.9213955023151, 1136.8408422820812, 1167.8014737494645, 7794.41541884361, 8122.553259570721, 9752.0, 9755.170810481117, 9833.31166818323, 3724.2365315110337, 3857.787821669779, 3869.4582758413844, 3921.529416469985, 3925.666318362914, 7959.283664470109, 7975.2423519765525, 7976.242256985577, 7995.0, 8000.319972802377, 4446.110777844444, 4457.821687132543, 4465.240909045405, 4469.508354081048, 4474.664400170001, 3834.6548810606623, 3963.4847469828737, 3977.423273625308, 4153.958460415357, 4158.9584104158575, 3165.5409965555327, 3278.246003419227, 3291.1772056985756, 3315.651856555075, 3324.9168770780925, 1029.5881647340973, 1042.1454407386022, 1051.1065594244983, 1055.6674647486018, 1061.0556604621893, 618.944295013451, 645.6836150286326, 647.1554621219354, 650.6681592387916, 656.9310222426672, 4616.907661846812, 4712.764361781898, 4840.588549973221, 4842.249451335048, 4962.528559786814, 496.77645059723267, 500.6145268143582, 507.45955557331325, 512.6667666017119, 525.8685328667833, 3040.7111324424145, 3217.4691175956173, 3245.837708114585, 3343.4726363686545, 3354.6477619849566, 8572.742817715478, 8833.07252738466, 8881.644734210688, 8883.244924181388, 8888.244499217639, 8720.0, 8781.121887811169, 8803.207711305886, 8823.867641985375, 8834.602442890086, 6763.763268285685, 6844.965775171092, 6862.451003919575, 6914.204866440323, 6925.757598484029, 4553.089382123522, 4589.2657174851865, 4593.379893714306, 4655.324977878121, 4716.834910778106, 7974.16271291506, 8041.155678653891, 8044.557549334842, 8066.516009039477, 8130.268275855202, 6630.7679231226675, 7832.921670783448, 8079.192080791874, 8130.471519351223, 8138.0, 4948.208286674115, 5666.631668941505, 5830.242068531234, 5871.823845284648, 5939.46544810969, 6780.69486873082, 6784.59292442455, 6847.075644788089, 6850.21222559412, 6853.143357080365, 6283.214598175228, 6391.840203994937, 6444.484441244783, 6510.3489651034515, 6527.640979745969, 6263.311035785969, 6299.30707622152, 6306.400891915444, 6352.333005034589, 6369.490440764635, 5603.243562119062, 5666.376698563073, 5673.5461163107675, 5677.602567820213, 5736.082226843685, 4114.979425102855, 4135.8966025849595, 4196.286631272752, 4207.684423668176, 4314.503832059353, 4171.770552619514, 4244.893877652959, 4265.274903266514, 4308.4399028126145, 4385.144896745104], "value": 3740.5237859270364, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.36826713378133, 333.96493368196326, 334.64360456114224, 356.50267876312535, 364.8996525955363, 5045.192769156931, 5127.282180494729, 5133.6663116897425, 5150.330457040589, 5153.196381266554, 4981.651284410093, 4991.376077990251, 5031.195008798593, 5034.5468907798295, 5045.419776725677, 3702.8150991682674, 3857.4926297906445, 3867.1878905429894, 3892.630200130983, 3895.590962948892, 3864.3044252034624, 3875.0, 3879.573246942834, 3880.9805950970203, 3907.1404291055924, 3845.1540661054664, 3848.499695039655, 3870.1098747288142, 3877.1276462795927, 3881.7282790204695, 3656.378415669316, 3834.482344883433, 3860.2279544091152, 3867.9613203868025, 3874.1864208516245, 3374.0889959710985, 3374.375740487993, 3375.5274261603395, 3386.508956201337, 3404.3361544498835, 4864.635152363551, 4869.5130486951375, 4876.317315575787, 4885.2427873679635, 4899.726071221472, 4373.737575745466, 4376.343548467724, 4385.451818522684, 4392.253316936129, 4405.801738921724, 5050.444451110375, 5205.7397130143345, 5224.242484839677, 5247.323864828964, 5256.211568264753, 3682.5949145593963, 3695.593484716679, 3695.815209239554, 3702.2780557791243, 3702.5742039665515, 5175.0, 5315.627906046617, 5487.64330318528, 5495.807646732345, 5499.0, 3666.2850744104644, 3737.8225858854375, 3779.962200377961, 3782.829772660237, 3789.0, 3810.1046254130256, 3827.770333779982, 3828.502294701671, 3837.597052309523, 3856.2646808936092, 4388.253996820548, 4397.40635014269, 4401.493828209733, 4421.977890110529, 4458.554144585515, 5130.948690513047, 5180.248863914787, 5218.6868787872845, 5238.873642166953, 5255.237990491358, 1281.4681907008674, 1295.0, 1296.4749276543016, 1305.1320871620267, 1305.5964837799213, 818.7502811642506, 868.9304855611535, 874.1258741258741, 884.1821315283337, 884.6107712606432, 4474.149911516802, 4477.19410506112, 4498.0, 4505.662075344362, 4511.1654343946475, 5216.9305292414965, 5254.868628284247, 5271.578273738169, 5273.419923808378, 5317.73411329432, 4261.659067274611, 4503.459584849839, 4507.6168525675685, 4515.71007542829, 4550.905384338682, 2741.6709994800353, 2783.860806959644, 2785.2174608250734, 2785.3454438207004, 2803.3972695870484, 4875.390576177978, 4897.865366165657, 4913.803447862117, 4932.1861892788, 4936.827211047668, 4861.975690121598, 5268.552173065255, 5316.840494785162, 5334.279872217278, 5351.973240133774, 4860.684055536379, 4864.030067822538, 4879.414470263591, 4886.068358984581, 4905.754712264301, 5203.297554830201, 5205.245239440335, 5245.711485868162, 5256.369235691742, 5300.515855560449, 4908.803647854046, 5089.8727531812, 5157.8452646420665, 5231.633785634969, 5232.0, 4765.547273009059, 4883.487233840538, 4883.780229889735, 4885.609151267961, 4893.21708526634, 3671.6695497405362, 3746.157114649291, 3762.5108735864164, 3765.566959799658, 3788.204477059738, 5372.597055220952, 5374.817540141163, 5397.217403476396, 5399.136138217867, 5403.270558474555, 4953.315872603188, 5196.194589838581, 5213.504717051874, 5215.895682086261, 5225.798066444663, 5224.7648855802345, 5255.211718242142, 5262.0, 5265.0, 5266.235811003204, 5165.405978312392, 5170.560502357342, 5195.7142357170715, 5196.946381822185, 5203.16749320107, 4939.851804445872, 5236.659617124875, 5298.0, 5304.230886521355, 5319.335083114611, 5153.845384638467, 5155.226715992631, 5163.0, 5170.1727723564045, 5194.3247377840635], "value": 3905.8755321320064, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.2088973889074, 628.3088602537209, 630.8675178212575, 635.9364063593638, 642.1876326447044, 12113.878861211386, 12873.742525149491, 12888.677783055427, 12890.871091289084, 13018.153820001693, 12151.817722734144, 12269.877301227007, 12289.631311060682, 12297.385130743472, 12316.261024338524, 771.1517330935953, 793.7936136604495, 796.1004065406083, 818.2063398503471, 825.63671984327, 812.7358608452247, 813.2639960835423, 813.3168138763438, 813.3818298093462, 813.4387272781762, 864.3344624639025, 865.0095640491634, 865.1435079271539, 865.35098675993, 867.1285358214985, 856.8029353248756, 856.9700060497853, 860.1914200651399, 862.1249431826669, 862.1981557151846, 622.6637615687523, 623.3953065526432, 623.9625622462667, 626.0640342687711, 630.4609558827228, 13370.264635445046, 13611.183329000292, 13694.0, 13726.725465490734, 13753.312334383341, 12718.491260349667, 13173.407196676173, 13204.0, 13325.944453597274, 13494.460221591125, 10557.208209384327, 10634.0, 10679.78640427187, 10856.782864342747, 10923.235373523856, 3129.937401251962, 3195.760317976161, 3206.695363940422, 3207.7433805295755, 3211.389835931189, 54130.0, 54784.0, 55047.72476137594, 55167.448325516634, 57247.427525724626, 10070.395776253448, 10130.0, 10151.1371533419, 10274.0, 10387.68836934893, 18524.907375463306, 18654.81345186558, 18784.81215187858, 18809.623807523774, 18980.715289270673, 6370.394812492806, 6383.968080159662, 6465.224173099165, 6494.0, 6546.235914349269, 3482.5995010574106, 3569.161247106928, 3591.6767490925417, 3624.909377265537, 3661.395869681526, 2955.453241150394, 3064.6628870824193, 3096.4581198290207, 3096.7367773739484, 3116.937661246762, 540.6188637010898, 546.4972225552501, 549.8240563019875, 557.969311687861, 560.1961185698535, 573.6414740787009, 601.3084952304797, 606.7421345927963, 613.8496068463255, 630.3979699387074, 8118.188181181835, 8391.748247552583, 8405.159484051546, 8407.159284071544, 8581.742547723577, 584.6316820403118, 604.4439116013191, 612.4426771637789, 616.605372561561, 621.0621960839076, 4024.5170579530645, 4134.297169481196, 4160.126373461547, 4162.313218319004, 4272.359146128106, 60112.69943650254, 60199.0, 62029.37970620326, 64739.676301618194, 65120.34879651238, 45955.0, 48682.26976595354, 50714.0, 51140.74429627902, 55013.0, 46375.76812115918, 46937.29594056092, 47427.762861185474, 47913.5208647916, 47976.0, 8658.0, 9105.726828195166, 9119.133682300171, 9130.132637399436, 9169.816603668023, 32019.0, 32915.0, 33122.50316245307, 33141.66858331482, 34169.0, 38751.418728719655, 40928.18143637051, 41956.79021604872, 42183.0, 42846.78576607097, 11145.2198346115, 11202.88797112051, 11232.584994226281, 11242.437878105899, 11403.087752979907, 11915.344656043653, 12394.132410731523, 12529.874701253235, 12727.172733772275, 13181.01142414342, 11464.656060318204, 11764.941175294069, 11812.822807658064, 12048.939755301168, 12086.818697719365, 11194.496247668712, 11237.7752444949, 11427.371494567878, 11461.312321260752, 11597.362145082101, 10253.9487302566, 10894.346339219672, 10993.120550356112, 11300.378479183724, 11305.26515776472, 9449.905500945177, 9485.336026478357, 9881.703548893545, 9919.454430006419, 10484.895151048391, 8436.43312862672, 8659.220670139717, 9319.114684105, 9343.34596578233, 9600.903990960001], "value": 6199.322021369766, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 115.0, 117.0, 117.0, 116.0, 117.0, 114.0, 116.0, 118.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 128.0, 133.0, 170.0, 128.0, 126.0, 131.0, 131.0, 150.0, 132.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 167.0, 172.0, 172.0, 169.0, 163.0, 164.0, 164.0, 163.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 111.0, 78.0, 79.0, 77.0, 79.0, 96.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 120.0, 85.0, 85.0, 84.0, 100.0, 85.0, 84.0, 105.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 118.0, 113.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 150.0, 152.0, 151.0, 151.0, 150.0, 150.0, 151.0, 152.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 100.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 71.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 92.0, 87.0, 93.0, 89.0, 88.0, 90.0, 91.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 152.0, 140.0, 150.0, 140.0, 143.0, 140.0, 143.0, 183.0, 148.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 195.0, 182.0, 189.0, 185.0, 186.0, 185.0, 192.0, 184.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 72.0, 72.0, 76.0, 71.0, 71.0, 71.0, 71.0, 72.0], "value": 72.9, "unit": "score"}]}]} 16:15:06 INFO - Return code: 0 16:15:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:15:06 ERROR - # TBPL SUCCESS # 16:15:06 INFO - Running post-action listener: _resource_record_post_action 16:15:06 INFO - [mozharness: 2017-02-16 00:15:06.675385Z] Finished run-tests step (success) 16:15:06 INFO - Running post-run listener: _resource_record_post_run 16:15:06 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 400650240; Read time: 0; Write time: 302360 16:15:06 INFO - TinderboxPrint: CPU usage
13.5% 16:15:06 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:15:06 INFO - TinderboxPrint: I/O write bytes / time
400,650,240 / 302,360 16:15:06 INFO - TinderboxPrint: CPU idle
8,298.4 (86.5%) 16:15:06 INFO - TinderboxPrint: CPU user
1,236.9 (12.9%) 16:15:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:15:06 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99954688; Read time: 0; Write time: 92108 16:15:06 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 299921408; Read time: 0; Write time: 210228 16:15:07 INFO - Running post-run listener: _upload_blobber_files 16:15:07 INFO - Blob upload gear active. 16:15:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:07 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 16:15:07 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'] 16:15:07 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 16:15:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:15:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:15:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:08 INFO - (blobuploader) - INFO - Done attempting. 16:15:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:11 INFO - (blobuploader) - INFO - Done attempting. 16:15:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:15:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:15:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:12 INFO - (blobuploader) - INFO - Done attempting. 16:15:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:15:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:15:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:13 INFO - (blobuploader) - INFO - Done attempting. 16:15:13 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:13 INFO - Return code: 0 16:15:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9f32d8ffb3d7ccbf4f50325e1381d46d702e12d061efa0833704d000729baf2fb087fe5de7b0a8058ff9401f8cb636b9827674d25f208fd7380c03735e09258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa32aac34640c776ede6eefbcc5617c36fbf4b61ea004d8928aafc17d45ed7450c51ab21818966aeef58e2a1f562ced5d239043d316c6060985ca46ef205ad82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2e4e8e43078f4bf4224657a5bdd7063faf0b0fb6ede60f18919ed70e0b823f25f669cad00b0b01d35e874823a819c86446ffacdcb33d0af4ff1bc30d553481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d964a3178d012d2016b96c706f96e0625d9eadc4f65bdfb83453316c005e76bf8d98e8ffc92c22aaeb0dd67540d06e2e3e02022f8dafc841c70b3bf01dcb02be"} 16:15:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:13 INFO - Contents: 16:15:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9f32d8ffb3d7ccbf4f50325e1381d46d702e12d061efa0833704d000729baf2fb087fe5de7b0a8058ff9401f8cb636b9827674d25f208fd7380c03735e09258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa32aac34640c776ede6eefbcc5617c36fbf4b61ea004d8928aafc17d45ed7450c51ab21818966aeef58e2a1f562ced5d239043d316c6060985ca46ef205ad82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2e4e8e43078f4bf4224657a5bdd7063faf0b0fb6ede60f18919ed70e0b823f25f669cad00b0b01d35e874823a819c86446ffacdcb33d0af4ff1bc30d553481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d964a3178d012d2016b96c706f96e0625d9eadc4f65bdfb83453316c005e76bf8d98e8ffc92c22aaeb0dd67540d06e2e3e02022f8dafc841c70b3bf01dcb02be"} 16:15:13 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:13 INFO - Copying logs to upload dir... 16:15:13 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.307637 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-15 16:15:13.986058) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 16:15:13.990019) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9f32d8ffb3d7ccbf4f50325e1381d46d702e12d061efa0833704d000729baf2fb087fe5de7b0a8058ff9401f8cb636b9827674d25f208fd7380c03735e09258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa32aac34640c776ede6eefbcc5617c36fbf4b61ea004d8928aafc17d45ed7450c51ab21818966aeef58e2a1f562ced5d239043d316c6060985ca46ef205ad82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2e4e8e43078f4bf4224657a5bdd7063faf0b0fb6ede60f18919ed70e0b823f25f669cad00b0b01d35e874823a819c86446ffacdcb33d0af4ff1bc30d553481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d964a3178d012d2016b96c706f96e0625d9eadc4f65bdfb83453316c005e76bf8d98e8ffc92c22aaeb0dd67540d06e2e3e02022f8dafc841c70b3bf01dcb02be"} build_url:https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Znn-nuAmQAmBsG85TGBJSg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9f32d8ffb3d7ccbf4f50325e1381d46d702e12d061efa0833704d000729baf2fb087fe5de7b0a8058ff9401f8cb636b9827674d25f208fd7380c03735e09258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa32aac34640c776ede6eefbcc5617c36fbf4b61ea004d8928aafc17d45ed7450c51ab21818966aeef58e2a1f562ced5d239043d316c6060985ca46ef205ad82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc2e4e8e43078f4bf4224657a5bdd7063faf0b0fb6ede60f18919ed70e0b823f25f669cad00b0b01d35e874823a819c86446ffacdcb33d0af4ff1bc30d553481", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d964a3178d012d2016b96c706f96e0625d9eadc4f65bdfb83453316c005e76bf8d98e8ffc92c22aaeb0dd67540d06e2e3e02022f8dafc841c70b3bf01dcb02be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-15 16:15:14.018074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:15:14.018650) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1487202852.36211-1298924462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-15 16:15:14.101049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 16:15:14.101608) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-15 16:15:14.102131) ========= ========= Total master_lag: 0.25 =========