builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1487823688.25 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.253184) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.253605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.253870) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.326425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.326797) ========= 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=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2017-02-22 20:21:28 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.197157 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.566214) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.566478) ========= 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=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.050534 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.626279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:28.626572) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:28,722 truncating revision to first 12 chars 2017-02-22 20:21:28,723 Setting DEBUG logging. 2017-02-22 20:21:28,723 attempt 1/10 2017-02-22 20:21:28,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 20:21:30,100 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.709433 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:30.348788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.349106) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.349595) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.349860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.361162) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 36 secs) (at 2017-02-22 20:21:30.361567) ========= /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=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:30 INFO - MultiFileLogger online at 20170222 20:21:30 in /builds/slave/test 20:21:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:30 INFO - {'append_to_log': False, 20:21:30 INFO - 'base_work_dir': '/builds/slave/test', 20:21:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:30 INFO - 'branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:30 INFO - 'config_files': ('talos/linux_config.py',), 20:21:30 INFO - 'default_actions': ('clobber', 20:21:30 INFO - 'read-buildbot-config', 20:21:30 INFO - 'download-and-extract', 20:21:30 INFO - 'populate-webroot', 20:21:30 INFO - 'create-virtualenv', 20:21:30 INFO - 'install', 20:21:30 INFO - 'run-tests'), 20:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:30 INFO - 'download_minidump_stackwalk': True, 20:21:30 INFO - 'download_symbols': 'ondemand', 20:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:30 INFO - 'gecko_profile': False, 20:21:30 INFO - 'gecko_profile_interval': 0, 20:21:30 INFO - 'installer_path': 'installer.exe', 20:21:30 INFO - 'log_level': 'info', 20:21:30 INFO - 'log_name': 'talos', 20:21:30 INFO - 'log_to_console': True, 20:21:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:30 INFO - 'opt_config_files': (), 20:21:30 INFO - 'pip_index': False, 20:21:30 INFO - 'suite': 'dromaeojs-e10s', 20:21:30 INFO - 'system_bits': '32', 20:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:30 INFO - 'title': 'talos-linux64-ix-059', 20:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:30 INFO - 'use_talos_json': True, 20:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:30 INFO - 'work_dir': 'build'} 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.695929Z] Running clobber step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: clobber 20:21:30 INFO - rmtree: /builds/slave/test/build 20:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:36 INFO - Running post-action listener: _resource_record_post_action 20:21:36 INFO - [mozharness: 2017-02-23 04:21:36.271079Z] Finished clobber step (success) 20:21:36 INFO - [mozharness: 2017-02-23 04:21:36.271215Z] Running read-buildbot-config step. 20:21:36 INFO - Running pre-action listener: _resource_record_pre_action 20:21:36 INFO - Running main action method: read_buildbot_config 20:21:36 INFO - Using buildbot properties: 20:21:36 INFO - { 20:21:36 INFO - "project": "", 20:21:36 INFO - "product": "firefox", 20:21:36 INFO - "who": "kwierso@gmail.com", 20:21:36 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:36 INFO - "stage_platform": "linux64", 20:21:36 INFO - "basedir": "/builds/slave/test", 20:21:36 INFO - "buildnumber": 690, 20:21:36 INFO - "platform": "ubuntu64_hw", 20:21:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:21:36 INFO - "slavebuilddir": "test", 20:21:36 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:36 INFO - "branch": "mozilla-central", 20:21:36 INFO - "script_repo_revision": "production", 20:21:36 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:36 INFO - "slavename": "talos-linux64-ix-059", 20:21:36 INFO - "repo_path": "mozilla-central" 20:21:36 INFO - } 20:21:36 INFO - Finding installer, test and symbols from parent task. 20:21:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:37 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:37 INFO - Running post-action listener: _resource_record_post_action 20:21:37 INFO - [mozharness: 2017-02-23 04:21:37.855962Z] Finished read-buildbot-config step (success) 20:21:37 INFO - [mozharness: 2017-02-23 04:21:37.856178Z] Running download-and-extract step. 20:21:37 INFO - Running pre-action listener: _resource_record_pre_action 20:21:37 INFO - Running main action method: download_and_extract 20:21:37 INFO - mkdir: /builds/slave/test/build/tests 20:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:37 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:21:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:21:38 INFO - Downloaded 896 bytes. 20:21:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:38 INFO - Using the following test package requirements: 20:21:38 INFO - {u'common': [u'target.common.tests.zip'], 20:21:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:38 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:38 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:38 INFO - u'target.web-platform.tests.zip'], 20:21:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:38 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:38 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:22:10 INFO - retry: attempt #1 caught exception: The read operation timed out 20:22:10 INFO - retry: Failed, sleeping 60 seconds before retrying 20:23:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #2 20:23:10 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:23:12 INFO - Expected file size: 57165889 20:23:12 INFO - Obtained file size: 57165889 20:23:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:23:22 INFO - Expected file size: 57165889 20:23:22 INFO - Obtained file size: 57165889 20:23:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 20:23:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:23:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:23:34 INFO - Expected file size: 14404319 20:23:34 INFO - Obtained file size: 14404319 20:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:34 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:23:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:23:35 INFO - Downloaded 61547669 bytes. 20:23:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:23:35 INFO - mkdir: /builds/slave/test/properties 20:23:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:23:35 INFO - Writing to file /builds/slave/test/properties/build_url 20:23:35 INFO - Contents: 20:23:35 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:23:35 INFO - Running post-action listener: _resource_record_post_action 20:23:35 INFO - Running post-action listener: set_extra_try_arguments 20:23:35 INFO - [mozharness: 2017-02-23 04:23:35.837877Z] Finished download-and-extract step (success) 20:23:35 INFO - [mozharness: 2017-02-23 04:23:35.837974Z] Running populate-webroot step. 20:23:35 INFO - Running pre-action listener: _resource_record_pre_action 20:23:35 INFO - Running main action method: populate_webroot 20:23:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:23:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:23:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:23:35 INFO - '--tppagecycles', 20:23:35 INFO - '7'], 20:23:35 INFO - 'tests': ['tsvgm']}}, 20:23:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:23:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:23:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:23:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:23:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:23:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['damp', 'tps']}, 20:23:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'tests': ['damp', 'tps']}, 20:23:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['dromaeo_dom']}, 20:23:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:23:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['basic_compositor_video']}, 20:23:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:23:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'other-e10s': {'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['a11yr', 20:23:35 INFO - 'ts_paint', 20:23:35 INFO - 'tpaint', 20:23:35 INFO - 'sessionrestore', 20:23:35 INFO - 'sessionrestore_no_auto_restore', 20:23:35 INFO - 'tabpaint']}, 20:23:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['tsvgx', 20:23:35 INFO - 'tsvgr_opacity', 20:23:35 INFO - 'tart', 20:23:35 INFO - 'tscrollx', 20:23:35 INFO - 'cart', 20:23:35 INFO - 'tsvg_static']}, 20:23:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:23:35 INFO - 'tsvgr_opacity', 20:23:35 INFO - 'tart', 20:23:35 INFO - 'tscrollx', 20:23:35 INFO - 'cart', 20:23:35 INFO - 'tsvg_static']}, 20:23:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'talos_options': ['--disable-e10s'], 20:23:35 INFO - 'tests': ['tp5o']}, 20:23:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:23:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'talos_options': ['--disable-e10s', 20:23:35 INFO - '--xperf_path', 20:23:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:35 INFO - 'tests': ['tp5n']}, 20:23:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:23:35 INFO - 'talos_options': ['--xperf_path', 20:23:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:23:35 INFO - 'tests': ['tp5n']}}, 20:23:35 INFO - 'talos.zip': {'path': '', 20:23:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:23:35 INFO - Running post-action listener: _resource_record_post_action 20:23:35 INFO - [mozharness: 2017-02-23 04:23:35.851198Z] Finished populate-webroot step (success) 20:23:35 INFO - [mozharness: 2017-02-23 04:23:35.851288Z] Running create-virtualenv step. 20:23:35 INFO - Running pre-action listener: _resource_record_pre_action 20:23:35 INFO - Running main action method: create_virtualenv 20:23:35 INFO - Creating virtualenv /builds/slave/test/build/venv 20:23:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:23:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:23:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:23:36 INFO - Using real prefix '/usr' 20:23:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:23:36 INFO - Installing distribute.............................................................................................................................................................................................done. 20:23:38 INFO - Installing pip.................done. 20:23:38 INFO - Return code: 0 20:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:23:38 INFO - Reading from file tmpfile_stdout 20:23:38 INFO - Output received: 20:23:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:23:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:23:38 INFO - Using env: {'DISPLAY': ':0', 20:23:38 INFO - 'HOME': '/home/cltbld', 20:23:38 INFO - 'LANG': 'en_US.UTF-8', 20:23:38 INFO - 'LANGUAGE': 'en_US:en', 20:23:38 INFO - 'LOGNAME': 'cltbld', 20:23:38 INFO - 'MAIL': '/var/mail/cltbld', 20:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:38 INFO - 'MOZ_NO_REMOTE': '1', 20:23:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:38 INFO - 'NO_EM_RESTART': '1', 20:23:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:38 INFO - 'PWD': '/builds/slave/test', 20:23:38 INFO - 'SHELL': '/bin/bash', 20:23:38 INFO - 'SHLVL': '1', 20:23:38 INFO - 'TERM': 'linux', 20:23:38 INFO - 'TMOUT': '86400', 20:23:38 INFO - 'USER': 'cltbld', 20:23:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:38 INFO - '_': '/tools/buildbot/bin/python'} 20:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:23:39 INFO - Cleaning up... 20:23:39 INFO - Return code: 0 20:23:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:23:39 INFO - Using env: {'DISPLAY': ':0', 20:23:39 INFO - 'HOME': '/home/cltbld', 20:23:39 INFO - 'LANG': 'en_US.UTF-8', 20:23:39 INFO - 'LANGUAGE': 'en_US:en', 20:23:39 INFO - 'LOGNAME': 'cltbld', 20:23:39 INFO - 'MAIL': '/var/mail/cltbld', 20:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:39 INFO - 'MOZ_NO_REMOTE': '1', 20:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:39 INFO - 'NO_EM_RESTART': '1', 20:23:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:39 INFO - 'PWD': '/builds/slave/test', 20:23:39 INFO - 'SHELL': '/bin/bash', 20:23:39 INFO - 'SHLVL': '1', 20:23:39 INFO - 'TERM': 'linux', 20:23:39 INFO - 'TMOUT': '86400', 20:23:39 INFO - 'USER': 'cltbld', 20:23:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:39 INFO - '_': '/tools/buildbot/bin/python'} 20:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:39 INFO - Downloading/unpacking psutil>=3.1.1 20:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:23:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:41 INFO - Installing collected packages: psutil 20:23:41 INFO - Running setup.py install for psutil 20:23:41 INFO - building 'psutil._psutil_linux' extension 20:23:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:23:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:23:42 INFO - building 'psutil._psutil_posix' extension 20:23:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:23:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:23:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:42 INFO - Successfully installed psutil 20:23:42 INFO - Cleaning up... 20:23:42 INFO - Return code: 0 20:23:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:23:42 INFO - Using env: {'DISPLAY': ':0', 20:23:42 INFO - 'HOME': '/home/cltbld', 20:23:42 INFO - 'LANG': 'en_US.UTF-8', 20:23:42 INFO - 'LANGUAGE': 'en_US:en', 20:23:42 INFO - 'LOGNAME': 'cltbld', 20:23:42 INFO - 'MAIL': '/var/mail/cltbld', 20:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:42 INFO - 'MOZ_NO_REMOTE': '1', 20:23:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:42 INFO - 'NO_EM_RESTART': '1', 20:23:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:42 INFO - 'PWD': '/builds/slave/test', 20:23:42 INFO - 'SHELL': '/bin/bash', 20:23:42 INFO - 'SHLVL': '1', 20:23:42 INFO - 'TERM': 'linux', 20:23:42 INFO - 'TMOUT': '86400', 20:23:42 INFO - 'USER': 'cltbld', 20:23:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:42 INFO - '_': '/tools/buildbot/bin/python'} 20:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:23:45 INFO - Installing collected packages: mozsystemmonitor 20:23:45 INFO - Running setup.py install for mozsystemmonitor 20:23:45 INFO - Successfully installed mozsystemmonitor 20:23:45 INFO - Cleaning up... 20:23:45 INFO - Return code: 0 20:23:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:23:45 INFO - Using env: {'DISPLAY': ':0', 20:23:45 INFO - 'HOME': '/home/cltbld', 20:23:45 INFO - 'LANG': 'en_US.UTF-8', 20:23:45 INFO - 'LANGUAGE': 'en_US:en', 20:23:45 INFO - 'LOGNAME': 'cltbld', 20:23:45 INFO - 'MAIL': '/var/mail/cltbld', 20:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:45 INFO - 'MOZ_NO_REMOTE': '1', 20:23:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:45 INFO - 'NO_EM_RESTART': '1', 20:23:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:45 INFO - 'PWD': '/builds/slave/test', 20:23:45 INFO - 'SHELL': '/bin/bash', 20:23:45 INFO - 'SHLVL': '1', 20:23:45 INFO - 'TERM': 'linux', 20:23:45 INFO - 'TMOUT': '86400', 20:23:45 INFO - 'USER': 'cltbld', 20:23:45 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:45 INFO - '_': '/tools/buildbot/bin/python'} 20:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:45 INFO - Downloading/unpacking jsonschema==2.5.1 20:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:23:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:47 INFO - Downloading functools32-3.2.3-2.tar.gz 20:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:23:47 INFO - warning: no files found matching '*.txt' 20:23:47 INFO - no previously-included directories found matching 'build' 20:23:47 INFO - no previously-included directories found matching 'dist' 20:23:47 INFO - no previously-included directories found matching '.git*' 20:23:47 INFO - Installing collected packages: jsonschema, functools32 20:23:47 INFO - Running setup.py install for functools32 20:23:48 INFO - warning: no files found matching '*.txt' 20:23:48 INFO - no previously-included directories found matching 'build' 20:23:48 INFO - no previously-included directories found matching 'dist' 20:23:48 INFO - no previously-included directories found matching '.git*' 20:23:48 INFO - Successfully installed jsonschema functools32 20:23:48 INFO - Cleaning up... 20:23:48 INFO - Return code: 0 20:23:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:23:48 INFO - Using env: {'DISPLAY': ':0', 20:23:48 INFO - 'HOME': '/home/cltbld', 20:23:48 INFO - 'LANG': 'en_US.UTF-8', 20:23:48 INFO - 'LANGUAGE': 'en_US:en', 20:23:48 INFO - 'LOGNAME': 'cltbld', 20:23:48 INFO - 'MAIL': '/var/mail/cltbld', 20:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:48 INFO - 'MOZ_NO_REMOTE': '1', 20:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:48 INFO - 'NO_EM_RESTART': '1', 20:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:48 INFO - 'PWD': '/builds/slave/test', 20:23:48 INFO - 'SHELL': '/bin/bash', 20:23:48 INFO - 'SHLVL': '1', 20:23:48 INFO - 'TERM': 'linux', 20:23:48 INFO - 'TMOUT': '86400', 20:23:48 INFO - 'USER': 'cltbld', 20:23:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:48 INFO - '_': '/tools/buildbot/bin/python'} 20:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:23:48 INFO - Cleaning up... 20:23:48 INFO - Return code: 0 20:23:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:23:48 INFO - Using env: {'DISPLAY': ':0', 20:23:48 INFO - 'HOME': '/home/cltbld', 20:23:48 INFO - 'LANG': 'en_US.UTF-8', 20:23:48 INFO - 'LANGUAGE': 'en_US:en', 20:23:48 INFO - 'LOGNAME': 'cltbld', 20:23:48 INFO - 'MAIL': '/var/mail/cltbld', 20:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:48 INFO - 'MOZ_NO_REMOTE': '1', 20:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:48 INFO - 'NO_EM_RESTART': '1', 20:23:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:48 INFO - 'PWD': '/builds/slave/test', 20:23:48 INFO - 'SHELL': '/bin/bash', 20:23:48 INFO - 'SHLVL': '1', 20:23:48 INFO - 'TERM': 'linux', 20:23:48 INFO - 'TMOUT': '86400', 20:23:48 INFO - 'USER': 'cltbld', 20:23:48 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:48 INFO - '_': '/tools/buildbot/bin/python'} 20:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:48 INFO - Downloading/unpacking blobuploader==1.2.4 20:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:50 INFO - Downloading blobuploader-1.2.4.tar.gz 20:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:23:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:23:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:51 INFO - Downloading docopt-0.6.1.tar.gz 20:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:23:51 INFO - Installing collected packages: blobuploader, requests, docopt 20:23:51 INFO - Running setup.py install for blobuploader 20:23:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:23:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:23:51 INFO - Running setup.py install for requests 20:23:51 INFO - Running setup.py install for docopt 20:23:51 INFO - Successfully installed blobuploader requests docopt 20:23:51 INFO - Cleaning up... 20:23:51 INFO - Return code: 0 20:23:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:23:51 INFO - Using env: {'DISPLAY': ':0', 20:23:51 INFO - 'HOME': '/home/cltbld', 20:23:51 INFO - 'LANG': 'en_US.UTF-8', 20:23:51 INFO - 'LANGUAGE': 'en_US:en', 20:23:51 INFO - 'LOGNAME': 'cltbld', 20:23:51 INFO - 'MAIL': '/var/mail/cltbld', 20:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:51 INFO - 'MOZ_NO_REMOTE': '1', 20:23:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:51 INFO - 'NO_EM_RESTART': '1', 20:23:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:51 INFO - 'PWD': '/builds/slave/test', 20:23:51 INFO - 'SHELL': '/bin/bash', 20:23:51 INFO - 'SHLVL': '1', 20:23:51 INFO - 'TERM': 'linux', 20:23:51 INFO - 'TMOUT': '86400', 20:23:51 INFO - 'USER': 'cltbld', 20:23:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:51 INFO - '_': '/tools/buildbot/bin/python'} 20:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:52 INFO - Running setup.py (path:/tmp/pip-_qT5r6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:52 INFO - Running setup.py (path:/tmp/pip-G7wX0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:52 INFO - Running setup.py (path:/tmp/pip-guhXgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:52 INFO - Running setup.py (path:/tmp/pip-RiJZnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:52 INFO - Running setup.py (path:/tmp/pip-IhKIs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:52 INFO - Running setup.py (path:/tmp/pip-4ArpP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:52 INFO - Running setup.py (path:/tmp/pip-bIubWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:52 INFO - Running setup.py (path:/tmp/pip-rkM5Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:52 INFO - Running setup.py (path:/tmp/pip-ctGV1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:52 INFO - Running setup.py (path:/tmp/pip-GFYd3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:52 INFO - Running setup.py (path:/tmp/pip-0U7P0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:52 INFO - Running setup.py (path:/tmp/pip-WSE3Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:53 INFO - Running setup.py (path:/tmp/pip-eXIr9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:53 INFO - Running setup.py (path:/tmp/pip-u5oP4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:53 INFO - Running setup.py (path:/tmp/pip-gXVEpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:53 INFO - Running setup.py (path:/tmp/pip-w_5Rsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:53 INFO - Running setup.py (path:/tmp/pip-QCHZ38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:23:53 INFO - Running setup.py install for manifestparser 20:23:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:23:53 INFO - Running setup.py install for mozcrash 20:23:53 INFO - Running setup.py install for mozdebug 20:23:53 INFO - Running setup.py install for mozdevice 20:23:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:23:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:23:53 INFO - Running setup.py install for mozfile 20:23:53 INFO - Running setup.py install for mozhttpd 20:23:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozinfo 20:23:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozInstall 20:23:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozleak 20:23:54 INFO - Running setup.py install for mozlog 20:23:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for moznetwork 20:23:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozprocess 20:23:54 INFO - Running setup.py install for mozprofile 20:23:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozrunner 20:23:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:23:54 INFO - Running setup.py install for mozscreenshot 20:23:54 INFO - Running setup.py install for moztest 20:23:55 INFO - Running setup.py install for mozversion 20:23:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:23:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:23:55 INFO - Cleaning up... 20:23:55 INFO - Return code: 0 20:23:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:23:55 INFO - Using env: {'DISPLAY': ':0', 20:23:55 INFO - 'HOME': '/home/cltbld', 20:23:55 INFO - 'LANG': 'en_US.UTF-8', 20:23:55 INFO - 'LANGUAGE': 'en_US:en', 20:23:55 INFO - 'LOGNAME': 'cltbld', 20:23:55 INFO - 'MAIL': '/var/mail/cltbld', 20:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:55 INFO - 'MOZ_NO_REMOTE': '1', 20:23:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:55 INFO - 'NO_EM_RESTART': '1', 20:23:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:55 INFO - 'PWD': '/builds/slave/test', 20:23:55 INFO - 'SHELL': '/bin/bash', 20:23:55 INFO - 'SHLVL': '1', 20:23:55 INFO - 'TERM': 'linux', 20:23:55 INFO - 'TMOUT': '86400', 20:23:55 INFO - 'USER': 'cltbld', 20:23:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:55 INFO - '_': '/tools/buildbot/bin/python'} 20:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:55 INFO - Running setup.py (path:/tmp/pip-Qv2hwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:55 INFO - Running setup.py (path:/tmp/pip-7Z5k40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:55 INFO - Running setup.py (path:/tmp/pip-nKfIqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:55 INFO - Running setup.py (path:/tmp/pip-pF35dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:55 INFO - Running setup.py (path:/tmp/pip-UJNUNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:55 INFO - Running setup.py (path:/tmp/pip-VAlesG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:55 INFO - Running setup.py (path:/tmp/pip-LOKJjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:55 INFO - Running setup.py (path:/tmp/pip-w8H36W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:55 INFO - Running setup.py (path:/tmp/pip-FEBIOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:56 INFO - Running setup.py (path:/tmp/pip-pTKfT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:56 INFO - Running setup.py (path:/tmp/pip-RTwFNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:56 INFO - Running setup.py (path:/tmp/pip-T0mN0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:56 INFO - Running setup.py (path:/tmp/pip-DufP3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:56 INFO - Running setup.py (path:/tmp/pip-9vpBTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:56 INFO - Running setup.py (path:/tmp/pip-vcrYs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:56 INFO - Running setup.py (path:/tmp/pip-VG8_ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:56 INFO - Running setup.py (path:/tmp/pip-r3th9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:23:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:23:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:58 INFO - Downloading blessings-1.6.tar.gz 20:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:23:58 INFO - Installing collected packages: blessings 20:23:58 INFO - Running setup.py install for blessings 20:23:58 INFO - Successfully installed blessings 20:23:58 INFO - Cleaning up... 20:23:58 INFO - Return code: 0 20:23:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:23:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:59 INFO - Reading from file tmpfile_stdout 20:23:59 INFO - Current package versions: 20:23:59 INFO - argparse == 1.2.1 20:23:59 INFO - blessings == 1.6 20:23:59 INFO - blobuploader == 1.2.4 20:23:59 INFO - docopt == 0.6.1 20:23:59 INFO - functools32 == 3.2.3-2 20:23:59 INFO - jsonschema == 2.5.1 20:23:59 INFO - manifestparser == 1.1 20:23:59 INFO - mozInstall == 1.12 20:23:59 INFO - mozcrash == 1.0 20:23:59 INFO - mozdebug == 0.1 20:23:59 INFO - mozdevice == 0.49 20:23:59 INFO - mozfile == 1.2 20:23:59 INFO - mozhttpd == 0.7 20:23:59 INFO - mozinfo == 0.9 20:23:59 INFO - mozleak == 0.1 20:23:59 INFO - mozlog == 3.4 20:23:59 INFO - moznetwork == 0.27 20:23:59 INFO - mozprocess == 0.25 20:23:59 INFO - mozprofile == 0.28 20:23:59 INFO - mozrunner == 6.13 20:23:59 INFO - mozscreenshot == 0.1 20:23:59 INFO - mozsystemmonitor == 0.3 20:23:59 INFO - moztest == 0.8 20:23:59 INFO - mozversion == 1.4 20:23:59 INFO - psutil == 3.1.1 20:23:59 INFO - requests == 1.2.3 20:23:59 INFO - wsgiref == 0.1.2 20:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:23:59 INFO - Using env: {'DISPLAY': ':0', 20:23:59 INFO - 'HOME': '/home/cltbld', 20:23:59 INFO - 'LANG': 'en_US.UTF-8', 20:23:59 INFO - 'LANGUAGE': 'en_US:en', 20:23:59 INFO - 'LOGNAME': 'cltbld', 20:23:59 INFO - 'MAIL': '/var/mail/cltbld', 20:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:59 INFO - 'MOZ_NO_REMOTE': '1', 20:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:59 INFO - 'NO_EM_RESTART': '1', 20:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:59 INFO - 'PWD': '/builds/slave/test', 20:23:59 INFO - 'SHELL': '/bin/bash', 20:23:59 INFO - 'SHLVL': '1', 20:23:59 INFO - 'TERM': 'linux', 20:23:59 INFO - 'TMOUT': '86400', 20:23:59 INFO - 'USER': 'cltbld', 20:23:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:59 INFO - '_': '/tools/buildbot/bin/python'} 20:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:23:59 INFO - Cleaning up... 20:23:59 INFO - Return code: 0 20:23:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c11b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, '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': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:23:59 INFO - Using env: {'DISPLAY': ':0', 20:23:59 INFO - 'HOME': '/home/cltbld', 20:23:59 INFO - 'LANG': 'en_US.UTF-8', 20:23:59 INFO - 'LANGUAGE': 'en_US:en', 20:23:59 INFO - 'LOGNAME': 'cltbld', 20:23:59 INFO - 'MAIL': '/var/mail/cltbld', 20:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:59 INFO - 'MOZ_NO_REMOTE': '1', 20:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:23:59 INFO - 'NO_EM_RESTART': '1', 20:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:59 INFO - 'PWD': '/builds/slave/test', 20:23:59 INFO - 'SHELL': '/bin/bash', 20:23:59 INFO - 'SHLVL': '1', 20:23:59 INFO - 'TERM': 'linux', 20:23:59 INFO - 'TMOUT': '86400', 20:23:59 INFO - 'USER': 'cltbld', 20:23:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:59 INFO - '_': '/tools/buildbot/bin/python'} 20:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:23:59 INFO - Cleaning up... 20:23:59 INFO - Return code: 0 20:23:59 INFO - Running post-action listener: _resource_record_post_action 20:23:59 INFO - Running post-action listener: _start_resource_monitoring 20:23:59 INFO - Starting resource monitoring. 20:23:59 INFO - [mozharness: 2017-02-23 04:23:59.696376Z] Finished create-virtualenv step (success) 20:23:59 INFO - [mozharness: 2017-02-23 04:23:59.696843Z] Running install step. 20:23:59 INFO - Running pre-action listener: _resource_record_pre_action 20:23:59 INFO - Running main action method: install 20:23:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:59 INFO - Reading from file tmpfile_stdout 20:23:59 INFO - Detecting whether we're running mozinstall >=1.0... 20:23:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:24:00 INFO - Reading from file tmpfile_stdout 20:24:00 INFO - Output received: 20:24:00 INFO - Usage: mozinstall [options] installer 20:24:00 INFO - Options: 20:24:00 INFO - -h, --help show this help message and exit 20:24:00 INFO - -d DEST, --destination=DEST 20:24:00 INFO - Directory to install application into. [default: 20:24:00 INFO - "/builds/slave/test"] 20:24:00 INFO - --app=APP Application being installed. [default: firefox] 20:24:00 INFO - mkdir: /builds/slave/test/build/application 20:24:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:24:15 INFO - Reading from file tmpfile_stdout 20:24:15 INFO - Output received: 20:24:15 INFO - /builds/slave/test/build/application/firefox/firefox 20:24:15 INFO - Running post-action listener: _resource_record_post_action 20:24:15 INFO - [mozharness: 2017-02-23 04:24:15.420841Z] Finished install step (success) 20:24:15 INFO - [mozharness: 2017-02-23 04:24:15.421178Z] Running run-tests step. 20:24:15 INFO - Running pre-action listener: _resource_record_pre_action 20:24:15 INFO - Running main action method: run_tests 20:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:24:15 INFO - Python 2.7.3 20:24:15 INFO - Return code: 0 20:24:15 INFO - grabbing minidump binary from tooltool 20:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:24:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c0f290>, '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 0x2c0fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c10040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:24:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:24:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:24:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:24:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:24:15 INFO - Return code: 0 20:24:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:24:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:24:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:24:15 INFO - ENV: RUST_BACKTRACE is now 1 20:24:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:24:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:24:15 INFO - Using env: {'DISPLAY': ':0', 20:24:15 INFO - 'HOME': '/home/cltbld', 20:24:15 INFO - 'LANG': 'en_US.UTF-8', 20:24:15 INFO - 'LANGUAGE': 'en_US:en', 20:24:15 INFO - 'LOGNAME': 'cltbld', 20:24:15 INFO - 'MAIL': '/var/mail/cltbld', 20:24:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:24:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:15 INFO - 'MOZ_NO_REMOTE': '1', 20:24:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:24:15 INFO - 'NO_EM_RESTART': '1', 20:24:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:15 INFO - 'PWD': '/builds/slave/test', 20:24:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:24:15 INFO - 'RUST_BACKTRACE': '1', 20:24:15 INFO - 'SHELL': '/bin/bash', 20:24:15 INFO - 'SHLVL': '1', 20:24:15 INFO - 'TERM': 'linux', 20:24:15 INFO - 'TMOUT': '86400', 20:24:15 INFO - 'USER': 'cltbld', 20:24:15 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856', 20:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:15 INFO - '_': '/tools/buildbot/bin/python'} 20:24:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:24:15 INFO - mozversion application_buildid: 20170221181700 20:24:15 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:24:15 INFO - mozversion application_display_name: Nightly 20:24:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:24:15 INFO - mozversion application_name: Firefox 20:24:15 INFO - mozversion application_remotingname: firefox 20:24:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:24:15 INFO - mozversion application_vendor: Mozilla 20:24:15 INFO - mozversion application_version: 54.0a1 20:24:15 INFO - mozversion platform_buildid: 20170221181700 20:24:15 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:24:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:24:15 INFO - mozversion platform_version: 54.0a1 20:24:15 INFO - using testdate: 1487823855 20:24:15 INFO - actual date: 1487823855 20:24:15 INFO - starting webserver on 'localhost:45187' 20:24:15 INFO - SUITE-START | Running 2 tests 20:24:15 INFO - TEST-START | dromaeo_css 20:24:15 INFO - operating with platform_type : linux_ 20:24:15 INFO - Initialising browser for dromaeo_css test... 20:24:15 INFO - TEST-INFO | started process 3335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLCL1Bj/profile http://localhost:45187/getInfo.html) 20:24:19 INFO - PROCESS | 3335 | Iterating over profile /tmp/tmpLCL1Bj/profile 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/blocklist.xml = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/content-prefs.sqlite = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/.parentlock = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/gmp = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:19 INFO - PROCESS | 3335 | Stat failure 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/crashes = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:19 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:19 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/addons.json = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/thumbnails = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/compatibility.ini = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/safebrowsing = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/directoryLinks.json = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/places.sqlite-wal = Wed Feb 22 2017 20:24:20 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/places.sqlite = Wed Feb 22 2017 20:24:20 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/frequencyCap.json = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/minidumps = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/extensions = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/extensions.ini = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/extensions.json = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | __metrics Screen width/height:1600/1200 20:24:20 INFO - PROCESS | 3335 | colorDepth:24 20:24:20 INFO - PROCESS | 3335 | Browser inner width/height: 1024/697 20:24:20 INFO - PROCESS | 3335 | __metrics 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/secmod.db = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/startupCache = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/user.js = Wed Feb 22 2017 20:24:15 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/cert8.db = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:24:19 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/cache2 = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/places.sqlite-shm = Wed Feb 22 2017 20:24:17 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/prefs.js = Wed Feb 22 2017 20:24:20 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/bookmarkbackups = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/key3.db = Wed Feb 22 2017 20:24:16 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/pluginreg.dat = Wed Feb 22 2017 20:24:18 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/permissions.sqlite = Wed Feb 22 2017 20:24:17 GMT-0800 (PST) 20:24:20 INFO - PROCESS | 3335 | No birth date. Using mtime. 20:24:20 INFO - PROCESS | 3335 | Using date: /tmp/tmpLCL1Bj/profile/localstore.rdf = Wed Feb 22 2017 20:23:34 GMT-0800 (PST) 20:24:21 ERROR - PROCESS | 3335 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:24:22 INFO - TEST-INFO | 3335: exit 0 20:24:22 INFO - Browser initialized. 20:24:22 INFO - Running cycle 1/1 for dromaeo_css test... 20:24:22 INFO - TEST-INFO | started process 3466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLCL1Bj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:34 INFO - PROCESS | 3466 | RSS: Main: 154091520 20:24:34 INFO - PROCESS | 3466 | 20:27:38 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45187/tests/dromaeo/cssquery-ext.html) 20:27:39 INFO - PROCESS | 3466 | RSS: Main: 165363712 20:27:39 INFO - PROCESS | 3466 | 20:29:22 INFO - PROCESS | 3466 | ************************* 20:29:22 INFO - PROCESS | 3466 | A coding exception was thrown and uncaught in a Task. 20:29:22 INFO - PROCESS | 3466 | 20:29:22 ERROR - PROCESS | 3466 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:29:22 INFO - PROCESS | 3466 | Full stack: 20:29:22 INFO - PROCESS | 3466 | ************************* 20:30:42 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-ext.html (next: http://localhost:45187/tests/dromaeo/cssquery-jquery.html) 20:30:42 INFO - PROCESS | 3466 | RSS: Main: 157921280 20:30:42 INFO - PROCESS | 3466 | 20:33:44 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45187/tests/dromaeo/cssquery-mootools.html) 20:33:45 INFO - PROCESS | 3466 | RSS: Main: 166731776 20:33:45 INFO - PROCESS | 3466 | 20:36:47 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45187/tests/dromaeo/cssquery-prototype.html) 20:36:48 INFO - PROCESS | 3466 | RSS: Main: 158371840 20:36:48 INFO - PROCESS | 3466 | 20:39:51 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45187/tests/dromaeo/cssquery-yui.html) 20:39:51 INFO - PROCESS | 3466 | RSS: Main: 165244928 20:39:51 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | Cycle 1(1): loaded http://localhost:45187/tests/dromaeo/cssquery-yui.html (next: http://localhost:45187/tests/dromaeo/cssquery-dojo.html) 20:42:54 INFO - PROCESS | 3466 | RSS: Main: 158662656 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | __start_tp_report 20:42:54 INFO - PROCESS | 3466 | _x_x_mozilla_page_load 20:42:54 INFO - PROCESS | 3466 | _x_x_mozilla_page_load_details 20:42:54 INFO - PROCESS | 3466 | |i|pagename|runs| 20:42:54 INFO - PROCESS | 3466 | |0;dojo.html;2516.8741562921855;2538.504991526652;2545.694516658001;2552.7702506774385;2555.5527782638032;4304.677149213813;4484.439445069366;4500.707454015491;4543.318502224668;4551.294549344852;4335.5230924598345;4515.05183911379;4539.092181563684;4561.315802629607;4620.768961551926;4186.309258972274;4231.661467082641;4241.172971270588;4254.149170165963;4298.290782020978;4275.337322714969;4292.29177185764;4301.591348821857;4315.654747620199;4359.106383191438;4070.3894415837567;4108.67130629549;4125.133721918401;4230.576942305775;4305.78471076448;4149.668026557868;4168.520620128664;4185.204811085914;4194.790260486994;4223.535411104776;4118.299889018874;4142.730722503027;4161.583841615845;4180.184863951531;4193.370994350872;4421.226285400042;4434.49003364614;4504.166729155117;4523.909521809578;4548.2495388260895;4749.382580264578;4766.76166191689;4767.880802979918;4769.689970151929;4777.737224452654;4743.644226682978;4747.691400058985;4758.809647614091;4758.857234282977;4806.75966201692;4450.933236001462;4508.954910450919;4516.20966330894;4516.638668906479;4525.366448697185;4846.39420072491;4883;4890.486498917562;4899.6570240083565;4914.5822605078965;4765.618750499952;4776.188048031843;4784;4791.520847915181;4836.105320515716;4835.6131509479155;4841.104395686809;4844.128056949712;4853.6602437829715;4859.514048595183;4615.907681846412;4646.303054541778;4801.807927682854;4813.95186048142;4825.420949486014;4752.477227504972;4777.880552986203;4782.306565547976;4803.471618122004;4850.029994001214;4117.958820411757;4125.814338354781;4126.154138401621;4160.126373461547;4171.83312667496;3520.6655367740022;3607.026102952188;3622.692071173927;3632.3824949758605;3649.671529562352;4137.54487006429;4196.307609244502;4213.346931225665;4225.112726327444;4264.189803937242;4826.662133650681;4846.927296090562;4853.3447984522345;4853.878653033702;4894;4218.6835987300465;4224.978875105605;4228.4080228768;4230.703850730481;4263.488381394191;4222.514410842731;4259.701820872509;4370.191514569815;4401.779911004501;4405.471343438744;4563.178627847019;4574.405327307429;4588.4952655207235;4614.584687378152;4620.283856002325;4766.785494652819;4799.784009719501;4813.15769740301;4832.806687732451;4841.878953026203;4557.361969324231;4562.22442184836;4564.7032942858605;4597.931031034604;4605.7927393268055;4593.311003349524;4615.723056616479;4704.647151463585;4729.55069268419;4754.429468463806;4815.494373090915;4816.084943860656;4823.758812059453;4862.95137048625;4866.853994380174;4692.507286734844;4692.671512994058;4698.812047518061;4708.952910470852;4749.928751068667;4668.183067963025;4686.062787442457;4694.483606803181;4705.153072446992;4744.217204161413;4674.322223277673;4682.086993036325;4686.531346865286;4686.789094490688;4733.124371991055;4741.525847415231;4749.453812811433;4764.047190561832;4768.523147685204;4805.783739731651;4505.121501307213;4541.477730061085;4695.22528782747;4701.670883038291;4737.0525894820485 20:42:54 INFO - PROCESS | 3466 | |1;ext.html;532.4356182446606;535.3629181274281;540.0791650236346;540.5864513647058;563.8844036972419;37563.933983630886;38548.80725596375;38618.420723689094;38669.419958700644;38743.41884871722;39258.60741392592;39599.45607614936;39878.401823972665;40098.19803603926;40260.59739402605;7529.736459223928;7783.41624378171;7790.065192176947;9131.08689131085;9376.296777741696;9284.814303713922;9386.671466498674;9392.953035234848;9416.482093484856;9418.246540276796;9411.247100231965;9554;9556.665516706882;9574.28192885533;9580.137787599115;9341.392809467397;9353.064693530661;9416.1525462708;9459;9478.952605236998;9360.859587106199;9393.154616084517;9415.387999779992;9453.52732363379;9453.90546094544;33032.33935321304;33588;33821.15447113817;33832.83083584591;33906.32187356239;3262.7383945697247;3422.144463884029;3437.295354452331;3483.3207524532727;3487.6337984511515;25511.489770204676;25531.36171595725;25640.358991025187;26011.349716257246;26243;3363.814990175539;3524.2951409718157;3575.4636804479537;3646.4165733482782;3687.1703866630064;237864;237902;239225;240564.99999999825;240714.99999999825;38783.418248726295;39828.2034359317;45681.85795355143;47043.70822522514;47558;44155.779221104334;47108.293375599394;47593.762031190316;47868;47969.28046079312;12014.519419223134;12091.879081209252;12109.69725756863;12121;12133.757324853454;8445.788855278544;8471.152884711479;8484.406091573654;8507.574621268912;8515;15395.537423944706;15546.689066218612;15620;15659.217039147998;15886.52340429789;6264;6339.2709838368255;6392.680365981775;6436.806895793134;6570.737170513126;728.2644529025722;734.0897287363631;745.0388998192271;761.4137114421975;763.6181909045478;25569.23292301234;26622.754491017964;26808;27201;27285.181444556696;866.5970323799463;889.768660148366;896.1441823058963;916.3585490156917;920.1672486399818;13827.377768000464;14378;14379.281035948161;14595.197264150362;14626.195559244345;314988;318161;319186;319544;320110;48572.7571362148;48873;49004;49064.26403603949;49170.50829491731;1153.2676750263643;1205.3189947679532;1207.3299318878107;1208.9516419343304;1213.342779313301;24447.75552244455;25074.24777256685;26292.21123366302;26418.73581264163;26472.338191544593;40711.592884071964;41158.1768364637;41173;41244;41270.58729412667;258379;259090;260421;260552;260642;23158;23847.40381490407;23889;25056.62415063812;25189.622155668047;37424;37593.2481350366;44000;44073.55926440695;45740.771296143306;35478.29043419169;37132.814335928146;38416.0236676932;38770.41844372393;39450.60549394469;34385.72992208206;37326.813365933;37586;37650.81174594109;38622;36071;36077.27845443129;36229.818850905576;36237.63762362343;37104.81447592745;31030;33481.83259083689;33700.66299337073;34392;34449.48325775166;25740.485190295716;26120.477590447703;26187.869060654575;26308.342291442867;26628.46743065089 20:42:54 INFO - PROCESS | 3466 | |2;jquery.html;390.0891418537714;394.5955395719389;401.53020965470404;406.912513809531;419.5133644971833;12737.108402411835;15999.280032398541;16498.752518712216;17841.91079044603;18032.819671803296;16973.830261697378;18335.908320458413;18372.081395930218;18527.444176674686;18694.252229910788;5074.74626268686;5280.762365693534;5294.602904782138;5391.16436952273;5617.045102332613;5636.351819540744;5658.915116273239;5680.715964201794;5697.8005769798065;5717.656940583557;5550.750216240279;5677.37548869624;5700.458456446631;5714.285714285715;5777.104548794943;5659.773609055633;5722.456366645183;5726.3700992890745;5738.081906894919;5750.309962804469;5451.454854514556;5515.889682206333;5529.253550770634;5619.662820230799;5637.689927054011;13339.333033348294;13585.864141358657;13615.455381784717;21174.517783755142;21404;2101.3275751759443;2117.1002324012234;2124.2246579998346;2152.2251989283845;2433.914812981537;9242.537873106317;9352.316583015054;9685.225181985425;9888;12927.806082908764;1675.0201132337597;1728.5419363868584;1762.0396883698415;1764.4882983934644;1797.7393277974752;80302.59848700718;81388.59305703493;82357.17642823554;82438;84126.57936710278;10683.83974240387;10869.347839129678;10987.725306867233;11153.888461115448;11253.606123785628;12201.69495762613;14928.70142597142;15501.302441390164;15503.14732689713;15508.689826203412;6568.113304703899;6753.932460675429;6903.102596662402;6910.101686780687;6924.134483189601;6292.716827742763;6304.621722696653;6338.366163383625;6479.352064793483;6480.837979050561;4053.9797301013305;4076.2458945095254;4149;4190.224808410454;4191.497020357577;1929.63336965976;2009.156154415149;2011.1955217912953;2012.5471768852185;2034.08466190215;761.6686741267566;773.5165521549031;791.2751244744271;798.4371018431991;803.863343231652;16858;18139.365122220657;18534.351297704514;18597;18973.525661858563;771.8880762289436;772.729544659376;777.3431450424382;780.5355813291083;799.6161842315605;9855.605775769032;10050.497475126214;10286.742831429125;10298.794024119476;10412.5834966602;470311;474477;475112;479884;481073;79837.60081199557;80492.59753701194;82953.58523207466;83448;83646.58176709077;911.0798093925183;918.669279059538;949.0699114867468;952.0146648219198;958.1472489484364;15251.542453726406;15692.76460853111;16207.837921620632;16284.547270504585;16442.424515141913;39132.217355653294;39262.21475570415;39491.605083949944;39692.60307397004;40595;48724.26913596228;52024.21963670472;52156;52382.21426678679;53447.19829202643;5174.818881339135;5210.869728256672;6793.830154246183;7532.8493430132185;8157.306628936607;8328.208820162075;11474.196806223485;12386.75226495483;12586.559470418491;12967.22196668203;10810.837837432287;10843.783124337311;10923.399213043358;11183.273087249303;11356.034737047114;10854;10896.400697961688;11087.168462365193;11440;11529.884701152881;10547.156227501628;10618.469076546296;10945.452727363441;11334.206605537533;11732.94133529327;6975.546589471668;7210.242924493063;7275.927240727737;8188.918110818816;8502.74491765248;10402.895971040496;10496.317739346918;10551.472426378497;10771.084457821173;10844 20:42:54 INFO - PROCESS | 3466 | |3;mootools.html;1341.5237590655317;1348.811166436699;1355.0920883008384;1357.4434481862438;1358.300475255243;7078.68145933433;7272.16370117436;7278.5632862028315;7607.847843043136;7613.505122167056;7547.886781698266;7658.54048757075;7669.309762121408;7680.462367634268;7716.498427602202;2002.6895831146196;2005.378332716855;2015.606956643455;2025.2911480981613;2032.756069271682;1988.5823976964855;2027.1182035814395;2038.9082491287927;2040.3470889315424;2047.2834507922257;2067.669172932331;2083.291680828518;2103.5372218111993;2105.7262555867715;2108.6099071671715;2033.7559492860878;2044.1516770540209;2048.1193086972594;2049.1291201239414;2052.79472052795;1713.2632967823831;1760.8855424397373;1995.4013795861183;1999.3502111813573;2046.6623007203793;7277.345038946467;7487.8502429951095;7490.176080631126;7492.7752167435065;7493.737719179802;4427.800748966304;4564.292534657134;4575.4738205106505;4585.426821647294;4586.678932474728;2759.889604415841;2779.805413621047;2780.3883145708114;2791.1766029021487;2802.369466869938;1153.1862256095185;1186.4898093819654;1670.3652612007481;1682.5457126575877;1759.7624320716668;9076.636934522678;9077.863832042525;9094.317926155498;9100.99889012208;9201.539923003824;3773.7924414156937;3785.3186426443503;4026.476558047435;4053;4072.165206132736;8038.477498962666;8090.1909809018625;8096.987876515435;8104.473209241499;8116.147804480444;4548.385967894292;4567.748773817472;4573.085382923428;4574.611158051536;4579.9084018319445;4015.6185162409524;4098.938515922264;4107.404426358161;4128.318827393447;4137.358709400048;3171.936561268808;3194.105650417887;3276.2137087098927;3281.5733954585753;3284.6040432815958;959.5154447004358;974.3228456222857;976.1556253840376;979.5053497983615;982.7837875667342;767.6699019421591;786.8898354230412;797.5613412623034;804.5213098206558;806.8381530595923;4618.214903466419;4747.976260118678;4774.164521208844;4802.639802014863;4954.653174277766;519.3248776590417;521.9347581552306;539.2827539372626;544.7113030094057;548.101114172757;3373.5951685797863;3489.5986961499243;3508.386032444312;3510.9122271942897;3575.9782983379005;8016.959915200387;8345.95827020861;8501.957490212633;8822.470651760916;9035.864462033074;9028.052054534312;9077.183053525214;9087.409318394415;9094.272458203328;9122.452652840851;6606.867862642816;7004.3696067354185;7055.858882822417;7065.399441047569;7074.504784665127;4759.3336932830125;4777.856664300077;4849.175640141255;4905.117078925828;5066.316047333563;7985.640646171052;8171.5505647189975;8201.425900187169;8229.806722730884;8270.37972152079;8419.663213471393;8463.322934165375;8472.322214222972;8538.530380829114;8545.572721363782;6462.7091780870915;6667.666616669244;6670.099536562696;6676.165479315086;6682.799516014527;7069.399101076466;7127.643617818984;7128.572285662877;7159.928400716134;7204.891926621;6433.099366088658;6497.772577959938;6573.605583664996;6694.430973367123;6717.832054198684;6420;6518;6534.771283005262;6616.933830661632;6657.3009833966735;5647.491725744702;5712.171735098304;5753.884922301447;5775.422457754191;5779.653220806765;3928.45001699768;3952.4268980997017;4029.7582145071387;4068.3897415386746;4146.0464093258115;4461.821527138878;4466.910661786811;4552.11233809404;4574.702644328108;4697.9530204698885 20:42:54 INFO - PROCESS | 3466 | |4;prototype.html;351.7326831607978;361.67087949965526;361.94932709420704;363.1321142469497;367.827121253011;5031.672941258816;5040.5211504907065;5086.92369614456;5093.617978651602;5093.898122037557;5011.248312753089;5049.747512624373;5053.191489361703;5054.444011158769;5057.620678449112;3895.0330083307963;4005.7396269242404;4018.7588744675268;4036.15240799431;4045.370509169049;4001.379786133154;4012.779297138656;4018.959810401888;4022.8994275143064;4037.8586749463775;3973.364261718126;4001.279769641463;4022.1754540319166;4038.838446462138;4070.14526949341;3667.2665466906724;3840.6735427488697;4055.5336136344404;4060.1879624075154;4069.7354671946227;3370.713489353408;3382.289719158955;3385.1706331948585;3386.0879643506564;3404.5063465797566;4857.757112144379;4895.4125504939275;4917.262410638397;4949.455559888409;4960.404751429815;4463.66522510813;4480;4490.730556166641;4494.550469993861;4495.14592227479;4973.254011898208;5241.449647787003;5242.947570524284;5250.65870718402;5252.763625636855;3722.8696995605296;3727.38498147808;3737.4580685800406;3744.3821769408014;3767;5247.475252474722;5504.366997795265;5529.944700553024;5560.221568980346;5572.637778544382;3505.8422370993367;3756.398976163801;3772.830222639988;3793.791341476245;3801.163743976321;3824.5028146341356;3832.865849695247;3844.6732027777393;3850.5356571794073;3866.5940076292145;4388.27593447084;4396.076609055007;4420.204363214588;4438.267685831802;4440.578145076212;5031.20767973778;5241.449647787003;5255.449642355515;5284.39229488606;5290.418054014055;1276.6467544402776;1282.557517656407;1290.1936289818864;1292.954746583884;1293.8641442648386;850.510956200175;877.5783231165552;887.9467231966103;889.755317287751;896.2381975320925;4468.4637843458995;4474.351219073216;4479.417675702203;4491.348754430589;4495.483019452739;5269.420363759983;5277.577793776489;5279.313689220377;5307.548858347007;5336.7865285388925;4271.402003719481;4457.258785846681;4458.286674132123;4468.687191896601;4479.939227955112;2795.434556648243;2806.7614252788335;2815.90144344951;2826.4064546445065;2831.377097038648;4919.532644398776;4932.654714169974;4933.777979990909;4939.8885250818275;4945.851624451272;5031.899362012739;5318.867028324323;5318.97340513295;5357.5446087082255;5359.785608575691;4862.6839255448285;4880.170371036932;4883.316335713003;4912.533309335536;4914.70511769295;5252.266751971763;5268.760776669977;5279.841604751864;5285.762140703754;5302.70835104073;4860.6111511078325;5108.157154069537;5144.768485418091;5180.539243859137;5220.399654039834;4812.711237325631;4862.00328932575;4865.739954230745;4922.735541650881;4949.727764972961;3697.759645623026;3769.453429252798;3807.942880856734;3814;3830.157365379612;5383.434739352311;5396.892062158657;5402.027635025733;5404.32445944257;5411.729413529387;4993.750312484434;5260.86847828792;5265.631405801557;5282.3132992712235;5283.0226408113085;5284.630075894805;5291.656042357235;5291.656042357235;5309.522143006993;5317.893642127058;5240.371155461369;5241.816536421207;5246.449122842047;5253.763580638804;5272.920906186334;5208.817691380784;5244.939980940054;5281.837995640953;5338.54622357104;5379.139337706105;5203.245529398292;5206.479352064764;5224.791008359775;5241.383445422646;5241.973790131177 20:42:54 INFO - PROCESS | 3466 | |5;yui.html;647.3106141958816;652.4226059936957;698.954567953083;735.6138027535543;750.197288900876;10926.28979116358;11595;11629.941850290747;11639.534418623267;11646.883531164685;12167.391630418466;12355.258684478938;12388.566400175972;12393.44229509672;12419.937900310508;777.7666699990009;792.528445574882;796.7052190689453;808.8382323535287;815.3966065111804;816.7386436340373;817.4727300890921;817.5094943034192;817.9059408168048;818.7011740714626;841.0874201491389;845;845.2646197807916;845.7166849105527;847.690592933581;835.1106072033296;835.5195762436593;836.5399030533182;838.304207507767;844.7803571071504;617.5491890919609;617.8949578571609;618.5731845026962;618.8298217990043;620.4230066038582;12761.361931903368;12926.741465170644;12999.935000324938;13081.476740930446;13166.736665266737;10637.095846853026;12139.757204855941;12173.756524869541;12913.612591622266;12916.354182290848;9801.656942006995;9938.453385063818;10000.799984000352;10014.9499252504;10107.545160467796;3073.754099672021;3121.172889184368;3152.7004934531183;3269.0683155300626;3271.509273608954;45385;45770.31344529835;52226.73886630581;60422;61149.38850611482;9082.636694532144;9185.770355741026;9289.396189247676;9322.813543729088;9330.253579713606;18200.63598728018;18203.54491137732;18254.543636409195;18271.817281827276;18319;6318.052292156213;6347.143135676624;6558.114654521674;6692.866142677217;6709.832254193587;3310.5696259486112;3365.899023029264;3472.2881809229048;3478.1826270826336;3479.7564170508326;2985.820850748962;3098.969010309868;3106.48742957414;3123.2816452216116;3148.7166155046157;491.7418355363463;499.83505443203654;502.11376919736693;504.11276153969277;511.289307862072;618.3476432363899;637.3021541412146;641.7176442365344;651.8761435327273;661.6063442251949;8021.478603890729;8518.233358997595;8571.571421428904;8720.738377848675;8821.691240806542;592.2596754057428;618.6813790897692;620.3269452643932;620.8292719502083;628.6071205496564;3808.409696497047;3827.13889374894;3918.1771827916455;4001.6598589119667;4001.8599349022643;62224;64018.35981640217;64212.35787642064;65750.34249657538;67455;56833;57283.713581431824;57599.42400575941;58245.70877145672;59144;52036.73981630067;52094.47905520972;52194.21708674373;52496.47503524916;52947.47052529502;7568.205338439385;8005.559694216874;8039.799005024921;8132;8222.712205072794;35678;36054.27891442104;36263.456048159824;36383.81808090943;37250.44124338083;32989.83505082459;43992.56007439885;44052.33921491244;44486.11027779362;45845.31232031589;11117.555297788322;11140.220184587;11224.887751122384;11364.14768892353;11369.431528423711;11898.821517677416;12262.20295680778;12672.619821405373;12806.167599106027;12991.805122922975;11211;11607.245529040585;11617.186796924134;11838.940805295919;12155.392230388268;10186.185105191715;10438.321509101885;10503.684889453329;10596.735081623021;10726.678199654023;10266;10548.736281593021;10619.575216991234;10687.839682404614;10923.508442120283;8771.91228087711;8876.822463550821;9053.90946090557;9108.089191080839;9126.406783559047;9325.626974920928;9330.720078397659;9369.109934556207;9387.10822471864;9526.285528585246 20:42:54 INFO - PROCESS | 3466 | __end_tp_report 20:42:54 INFO - PROCESS | 3466 | __start_cc_report 20:42:54 INFO - PROCESS | 3466 | _x_x_mozilla_cycle_collect,385 20:42:54 INFO - PROCESS | 3466 | __end_cc_report 20:42:54 INFO - PROCESS | 3466 | __startTimestamp1487824974865__endTimestamp 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | ------- Summary: start ------- 20:42:54 INFO - PROCESS | 3466 | Number of tests: 6 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#0] dojo.html Cycles:165 Average:4471.15 Median:4593.31 stddev:450.30 (9.8%) stddev-sans-first:424.78 20:42:54 INFO - PROCESS | 3466 | Values: 2516.9 2538.5 2545.7 2552.8 2555.6 4304.7 4484.4 4500.7 4543.3 4551.3 4335.5 4515.1 4539.1 4561.3 4620.8 4186.3 4231.7 4241.2 4254.1 4298.3 4275.3 4292.3 4301.6 4315.7 4359.1 4070.4 4108.7 4125.1 4230.6 4305.8 4149.7 4168.5 4185.2 4194.8 4223.5 4118.3 4142.7 4161.6 4180.2 4193.4 4421.2 4434.5 4504.2 4523.9 4548.2 4749.4 4766.8 4767.9 4769.7 4777.7 4743.6 4747.7 4758.8 4758.9 4806.8 4450.9 4509.0 4516.2 4516.6 4525.4 4846.4 4883.0 4890.5 4899.7 4914.6 4765.6 4776.2 4784.0 4791.5 4836.1 4835.6 4841.1 4844.1 4853.7 4859.5 4615.9 4646.3 4801.8 4814.0 4825.4 4752.5 4777.9 4782.3 4803.5 4850.0 4118.0 4125.8 4126.2 4160.1 4171.8 3520.7 3607.0 3622.7 3632.4 3649.7 4137.5 4196.3 4213.3 4225.1 4264.2 4826.7 4846.9 4853.3 4853.9 4894.0 4218.7 4225.0 4228.4 4230.7 4263.5 4222.5 4259.7 4370.2 4401.8 4405.5 4563.2 4574.4 4588.5 4614.6 4620.3 4766.8 4799.8 4813.2 4832.8 4841.9 4557.4 4562.2 4564.7 4597.9 4605.8 4593.3 4615.7 4704.6 4729.6 4754.4 4815.5 4816.1 4823.8 4863.0 4866.9 4692.5 4692.7 4698.8 4709.0 4749.9 4668.2 4686.1 4694.5 4705.2 4744.2 4674.3 4682.1 4686.5 4686.8 4733.1 4741.5 4749.5 4764.0 4768.5 4805.8 4505.1 4541.5 4695.2 4701.7 4737.1 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#1] ext.html Cycles:180 Average:42740.07 Median:37574.97 stddev:71744.38 (190.9%) stddev-sans-first:71875.65 20:42:54 INFO - PROCESS | 3466 | Values: 532.4 535.4 540.1 540.6 563.9 37563.9 38548.8 38618.4 38669.4 38743.4 39258.6 39599.5 39878.4 40098.2 40260.6 7529.7 7783.4 7790.1 9131.1 9376.3 9284.8 9386.7 9393.0 9416.5 9418.2 9411.2 9554.0 9556.7 9574.3 9580.1 9341.4 9353.1 9416.2 9459.0 9479.0 9360.9 9393.2 9415.4 9453.5 9453.9 33032.3 33588.0 33821.2 33832.8 33906.3 3262.7 3422.1 3437.3 3483.3 3487.6 25511.5 25531.4 25640.4 26011.3 26243.0 3363.8 3524.3 3575.5 3646.4 3687.2 237864.0 237902.0 239225.0 240565.0 240715.0 38783.4 39828.2 45681.9 47043.7 47558.0 44155.8 47108.3 47593.8 47868.0 47969.3 12014.5 12091.9 12109.7 12121.0 12133.8 8445.8 8471.2 8484.4 8507.6 8515.0 15395.5 15546.7 15620.0 15659.2 15886.5 6264.0 6339.3 6392.7 6436.8 6570.7 728.3 734.1 745.0 761.4 763.6 25569.2 26622.8 26808.0 27201.0 27285.2 866.6 889.8 896.1 916.4 920.2 13827.4 14378.0 14379.3 14595.2 14626.2 314988.0 318161.0 319186.0 319544.0 320110.0 48572.8 48873.0 49004.0 49064.3 49170.5 1153.3 1205.3 1207.3 1209.0 1213.3 24447.8 25074.2 26292.2 26418.7 26472.3 40711.6 41158.2 41173.0 41244.0 41270.6 258379.0 259090.0 260421.0 260552.0 260642.0 23158.0 23847.4 23889.0 25056.6 25189.6 37424.0 37593.2 44000.0 44073.6 45740.8 35478.3 37132.8 38416.0 38770.4 39450.6 34385.7 37326.8 37586.0 37650.8 38622.0 36071.0 36077.3 36229.8 36237.6 37104.8 31030.0 33481.8 33700.7 34392.0 34449.5 25740.5 26120.5 26187.9 26308.3 26628.5 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#2] jquery.html Cycles:180 Average:27411.22 Median:2284.26 stddev:78473.95 (3435.4%) stddev-sans-first:78667.86 20:42:54 INFO - PROCESS | 3466 | Values: 390.1 394.6 401.5 406.9 419.5 12737.1 15999.3 16498.8 17841.9 18032.8 16973.8 18335.9 18372.1 18527.4 18694.3 5074.7 5280.8 5294.6 5391.2 5617.0 5636.4 5658.9 5680.7 5697.8 5717.7 5550.8 5677.4 5700.5 5714.3 5777.1 5659.8 5722.5 5726.4 5738.1 5750.3 5451.5 5515.9 5529.3 5619.7 5637.7 13339.3 13585.9 13615.5 21174.5 21404.0 2101.3 2117.1 2124.2 2152.2 2433.9 9242.5 9352.3 9685.2 9888.0 12927.8 1675.0 1728.5 1762.0 1764.5 1797.7 80302.6 81388.6 82357.2 82438.0 84126.6 10683.8 10869.3 10987.7 11153.9 11253.6 12201.7 14928.7 15501.3 15503.1 15508.7 6568.1 6753.9 6903.1 6910.1 6924.1 6292.7 6304.6 6338.4 6479.4 6480.8 4054.0 4076.2 4149.0 4190.2 4191.5 1929.6 2009.2 2011.2 2012.5 2034.1 761.7 773.5 791.3 798.4 803.9 16858.0 18139.4 18534.4 18597.0 18973.5 771.9 772.7 777.3 780.5 799.6 9855.6 10050.5 10286.7 10298.8 10412.6 470311.0 474477.0 475112.0 479884.0 481073.0 79837.6 80492.6 82953.6 83448.0 83646.6 911.1 918.7 949.1 952.0 958.1 15251.5 15692.8 16207.8 16284.5 16442.4 39132.2 39262.2 39491.6 39692.6 40595.0 48724.3 52024.2 52156.0 52382.2 53447.2 5174.8 5210.9 6793.8 7532.8 8157.3 8328.2 11474.2 12386.8 12586.6 12967.2 10810.8 10843.8 10923.4 11183.3 11356.0 10854.0 10896.4 11087.2 11440.0 11529.9 10547.2 10618.5 10945.5 11334.2 11732.9 6975.5 7210.2 7275.9 8188.9 8502.7 10402.9 10496.3 10551.5 10771.1 10844.0 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#3] mootools.html Cycles:180 Average:4824.66 Median:520.63 stddev:2643.52 (507.8%) stddev-sans-first:2637.98 20:42:54 INFO - PROCESS | 3466 | Values: 1341.5 1348.8 1355.1 1357.4 1358.3 7078.7 7272.2 7278.6 7607.8 7613.5 7547.9 7658.5 7669.3 7680.5 7716.5 2002.7 2005.4 2015.6 2025.3 2032.8 1988.6 2027.1 2038.9 2040.3 2047.3 2067.7 2083.3 2103.5 2105.7 2108.6 2033.8 2044.2 2048.1 2049.1 2052.8 1713.3 1760.9 1995.4 1999.4 2046.7 7277.3 7487.9 7490.2 7492.8 7493.7 4427.8 4564.3 4575.5 4585.4 4586.7 2759.9 2779.8 2780.4 2791.2 2802.4 1153.2 1186.5 1670.4 1682.5 1759.8 9076.6 9077.9 9094.3 9101.0 9201.5 3773.8 3785.3 4026.5 4053.0 4072.2 8038.5 8090.2 8097.0 8104.5 8116.1 4548.4 4567.7 4573.1 4574.6 4579.9 4015.6 4098.9 4107.4 4128.3 4137.4 3171.9 3194.1 3276.2 3281.6 3284.6 959.5 974.3 976.2 979.5 982.8 767.7 786.9 797.6 804.5 806.8 4618.2 4748.0 4774.2 4802.6 4954.7 519.3 521.9 539.3 544.7 548.1 3373.6 3489.6 3508.4 3510.9 3576.0 8017.0 8346.0 8502.0 8822.5 9035.9 9028.1 9077.2 9087.4 9094.3 9122.5 6606.9 7004.4 7055.9 7065.4 7074.5 4759.3 4777.9 4849.2 4905.1 5066.3 7985.6 8171.6 8201.4 8229.8 8270.4 8419.7 8463.3 8472.3 8538.5 8545.6 6462.7 6667.7 6670.1 6676.2 6682.8 7069.4 7127.6 7128.6 7159.9 7204.9 6433.1 6497.8 6573.6 6694.4 6717.8 6420.0 6518.0 6534.8 6616.9 6657.3 5647.5 5712.2 5753.9 5775.4 5779.7 3928.5 3952.4 4029.8 4068.4 4146.0 4461.8 4466.9 4552.1 4574.7 4698.0 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#4] prototype.html Cycles:180 Average:4330.00 Median:4915.98 stddev:1250.91 (25.4%) stddev-sans-first:1218.26 20:42:54 INFO - PROCESS | 3466 | Values: 351.7 361.7 361.9 363.1 367.8 5031.7 5040.5 5086.9 5093.6 5093.9 5011.2 5049.7 5053.2 5054.4 5057.6 3895.0 4005.7 4018.8 4036.2 4045.4 4001.4 4012.8 4019.0 4022.9 4037.9 3973.4 4001.3 4022.2 4038.8 4070.1 3667.3 3840.7 4055.5 4060.2 4069.7 3370.7 3382.3 3385.2 3386.1 3404.5 4857.8 4895.4 4917.3 4949.5 4960.4 4463.7 4480.0 4490.7 4494.6 4495.1 4973.3 5241.4 5242.9 5250.7 5252.8 3722.9 3727.4 3737.5 3744.4 3767.0 5247.5 5504.4 5529.9 5560.2 5572.6 3505.8 3756.4 3772.8 3793.8 3801.2 3824.5 3832.9 3844.7 3850.5 3866.6 4388.3 4396.1 4420.2 4438.3 4440.6 5031.2 5241.4 5255.4 5284.4 5290.4 1276.6 1282.6 1290.2 1293.0 1293.9 850.5 877.6 887.9 889.8 896.2 4468.5 4474.4 4479.4 4491.3 4495.5 5269.4 5277.6 5279.3 5307.5 5336.8 4271.4 4457.3 4458.3 4468.7 4479.9 2795.4 2806.8 2815.9 2826.4 2831.4 4919.5 4932.7 4933.8 4939.9 4945.9 5031.9 5318.9 5319.0 5357.5 5359.8 4862.7 4880.2 4883.3 4912.5 4914.7 5252.3 5268.8 5279.8 5285.8 5302.7 4860.6 5108.2 5144.8 5180.5 5220.4 4812.7 4862.0 4865.7 4922.7 4949.7 3697.8 3769.5 3807.9 3814.0 3830.2 5383.4 5396.9 5402.0 5404.3 5411.7 4993.8 5260.9 5265.6 5282.3 5283.0 5284.6 5291.7 5291.7 5309.5 5317.9 5240.4 5241.8 5246.4 5253.8 5272.9 5208.8 5244.9 5281.8 5338.5 5379.1 5203.2 5206.5 5224.8 5241.4 5242.0 20:42:54 INFO - PROCESS | 3466 | 20:42:54 INFO - PROCESS | 3466 | [#5] yui.html Cycles:180 Average:14191.53 Median:52065.61 stddev:17666.46 (33.9%) stddev-sans-first:17686.74 20:42:54 INFO - PROCESS | 3466 | Values: 647.3 652.4 699.0 735.6 750.2 10926.3 11595.0 11629.9 11639.5 11646.9 12167.4 12355.3 12388.6 12393.4 12419.9 777.8 792.5 796.7 808.8 815.4 816.7 817.5 817.5 817.9 818.7 841.1 845.0 845.3 845.7 847.7 835.1 835.5 836.5 838.3 844.8 617.5 617.9 618.6 618.8 620.4 12761.4 12926.7 12999.9 13081.5 13166.7 10637.1 12139.8 12173.8 12913.6 12916.4 9801.7 9938.5 10000.8 10014.9 10107.5 3073.8 3121.2 3152.7 3269.1 3271.5 45385.0 45770.3 52226.7 60422.0 61149.4 9082.6 9185.8 9289.4 9322.8 9330.3 18200.6 18203.5 18254.5 18271.8 18319.0 6318.1 6347.1 6558.1 6692.9 6709.8 3310.6 3365.9 3472.3 3478.2 3479.8 2985.8 3099.0 3106.5 3123.3 3148.7 491.7 499.8 502.1 504.1 511.3 618.3 637.3 641.7 651.9 661.6 8021.5 8518.2 8571.6 8720.7 8821.7 592.3 618.7 620.3 620.8 628.6 3808.4 3827.1 3918.2 4001.7 4001.9 62224.0 64018.4 64212.4 65750.3 67455.0 56833.0 57283.7 57599.4 58245.7 59144.0 52036.7 52094.5 52194.2 52496.5 52947.5 7568.2 8005.6 8039.8 8132.0 8222.7 35678.0 36054.3 36263.5 36383.8 37250.4 32989.8 43992.6 44052.3 44486.1 45845.3 11117.6 11140.2 11224.9 11364.1 11369.4 11898.8 12262.2 12672.6 12806.2 12991.8 11211.0 11607.2 11617.2 11838.9 12155.4 10186.2 10438.3 10503.7 10596.7 10726.7 10266.0 10548.7 10619.6 10687.8 10923.5 8771.9 8876.8 9053.9 9108.1 9126.4 9325.6 9330.7 9369.1 9387.1 9526.3 20:42:54 INFO - PROCESS | 3466 | -------- Summary: end -------- 20:42:54 INFO - PROCESS | 3466 | 20:42:55 INFO - TEST-INFO | 3466: exit 0 20:42:55 INFO - TEST-OK | dromaeo_css | took 1119851ms 20:42:55 INFO - TEST-START | kraken 20:42:55 INFO - operating with platform_type : linux_ 20:42:55 INFO - Initialising browser for kraken test... 20:42:55 INFO - TEST-INFO | started process 3838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcVoXS_/profile http://localhost:45187/getInfo.html) 20:42:58 INFO - PROCESS | 3838 | Iterating over profile /tmp/tmpcVoXS_/profile 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/blocklist.xml = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/content-prefs.sqlite = Wed Feb 22 2017 20:42:57 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/.parentlock = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/gmp = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | Stat failure 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/crashes = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/addons.json = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/thumbnails = Wed Feb 22 2017 20:42:57 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/compatibility.ini = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/safebrowsing = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/directoryLinks.json = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/places.sqlite-wal = Wed Feb 22 2017 20:42:59 GMT-0800 (PST) 20:42:59 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:42:59 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/places.sqlite = Wed Feb 22 2017 20:42:59 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | __metrics Screen width/height:1600/1200 20:43:00 INFO - PROCESS | 3838 | colorDepth:24 20:43:00 INFO - PROCESS | 3838 | Browser inner width/height: 1024/697 20:43:00 INFO - PROCESS | 3838 | __metrics 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/frequencyCap.json = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/minidumps = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/extensions = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/extensions.ini = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/extensions.json = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/secmod.db = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/startupCache = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/user.js = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/cert8.db = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:42:59 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/cache2 = Wed Feb 22 2017 20:42:55 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/places.sqlite-shm = Wed Feb 22 2017 20:42:57 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/prefs.js = Wed Feb 22 2017 20:23:34 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/bookmarkbackups = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/key3.db = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/pluginreg.dat = Wed Feb 22 2017 20:42:58 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/permissions.sqlite = Wed Feb 22 2017 20:42:56 GMT-0800 (PST) 20:43:00 INFO - PROCESS | 3838 | No birth date. Using mtime. 20:43:00 INFO - PROCESS | 3838 | Using date: /tmp/tmpcVoXS_/profile/localstore.rdf = Wed Feb 22 2017 20:23:34 GMT-0800 (PST) 20:43:00 ERROR - PROCESS | 3838 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:43:01 INFO - TEST-INFO | 3838: exit 0 20:43:01 INFO - Browser initialized. 20:43:01 INFO - Running cycle 1/1 for kraken test... 20:43:01 INFO - TEST-INFO | started process 3968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcVoXS_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:43:13 INFO - PROCESS | 3968 | RSS: Main: 153722880 20:43:13 INFO - PROCESS | 3968 | 20:43:16 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45187/tests/kraken/driver.html?testName=audio-beat-detection) 20:43:17 INFO - PROCESS | 3968 | RSS: Main: 159834112 20:43:17 INFO - PROCESS | 3968 | 20:43:20 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45187/tests/kraken/driver.html?testName=audio-dft) 20:43:20 INFO - PROCESS | 3968 | RSS: Main: 159186944 20:43:20 INFO - PROCESS | 3968 | 20:43:23 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45187/tests/kraken/driver.html?testName=audio-fft) 20:43:24 INFO - PROCESS | 3968 | RSS: Main: 155705344 20:43:24 INFO - PROCESS | 3968 | 20:43:26 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45187/tests/kraken/driver.html?testName=audio-oscillator) 20:43:27 INFO - PROCESS | 3968 | RSS: Main: 156446720 20:43:27 INFO - PROCESS | 3968 | 20:43:29 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45187/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:43:30 INFO - PROCESS | 3968 | RSS: Main: 157061120 20:43:30 INFO - PROCESS | 3968 | 20:43:33 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45187/tests/kraken/driver.html?testName=imaging-darkroom) 20:43:33 INFO - PROCESS | 3968 | RSS: Main: 161980416 20:43:33 INFO - PROCESS | 3968 | 20:43:37 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45187/tests/kraken/driver.html?testName=imaging-desaturate) 20:43:37 INFO - PROCESS | 3968 | RSS: Main: 158707712 20:43:37 INFO - PROCESS | 3968 | 20:43:40 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45187/tests/kraken/driver.html?testName=json-parse-financial) 20:43:41 INFO - PROCESS | 3968 | RSS: Main: 159703040 20:43:41 INFO - PROCESS | 3968 | 20:43:43 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45187/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:43:43 INFO - PROCESS | 3968 | RSS: Main: 159764480 20:43:43 INFO - PROCESS | 3968 | 20:43:45 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:43:46 INFO - PROCESS | 3968 | RSS: Main: 159817728 20:43:46 INFO - PROCESS | 3968 | 20:43:48 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:43:49 INFO - PROCESS | 3968 | RSS: Main: 159612928 20:43:49 INFO - PROCESS | 3968 | 20:43:52 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:43:52 INFO - PROCESS | 3968 | RSS: Main: 160608256 20:43:52 INFO - PROCESS | 3968 | 20:43:56 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:43:56 INFO - PROCESS | 3968 | RSS: Main: 163094528 20:43:56 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:45187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45187/tests/kraken/driver.html?testName=ai-astar) 20:43:58 INFO - PROCESS | 3968 | RSS: Main: 164356096 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | __start_tp_report 20:43:58 INFO - PROCESS | 3968 | _x_x_mozilla_page_load 20:43:58 INFO - PROCESS | 3968 | _x_x_mozilla_page_load_details 20:43:58 INFO - PROCESS | 3968 | |i|pagename|runs| 20:43:58 INFO - PROCESS | 3968 | |0;ai-astar;106;116;117;118;117;119;118;122;117;120 20:43:58 INFO - PROCESS | 3968 | |1;audio-beat-detection;151;129;133;176;131;130;134;131;153;128 20:43:58 INFO - PROCESS | 3968 | |2;audio-dft;165;164;165;168;167;169;165;163;171;163 20:43:58 INFO - PROCESS | 3968 | |3;audio-fft;76;77;78;119;80;80;78;79;94;82 20:43:58 INFO - PROCESS | 3968 | |4;audio-oscillator;84;84;109;85;85;87;100;88;85;106 20:43:58 INFO - PROCESS | 3968 | |5;imaging-gaussian-blur;116;115;114;116;117;118;114;113;114;114 20:43:58 INFO - PROCESS | 3968 | |6;imaging-darkroom;151;150;151;152;151;151;150;175;150;151 20:43:58 INFO - PROCESS | 3968 | |7;imaging-desaturate;97;96;96;96;96;97;96;97;96;97 20:43:58 INFO - PROCESS | 3968 | |8;json-parse-financial;65;64;66;66;70;67;68;66;66;65 20:43:58 INFO - PROCESS | 3968 | |9;json-stringify-tinderbox;66;66;66;65;66;66;65;67;66;66 20:43:58 INFO - PROCESS | 3968 | |10;stanford-crypto-aes;94;97;92;90;89;88;87;87;86;96 20:43:58 INFO - PROCESS | 3968 | |11;stanford-crypto-ccm;140;138;149;139;138;140;152;140;192;138 20:43:58 INFO - PROCESS | 3968 | |12;stanford-crypto-pbkdf2;188;188;189;190;186;184;181;191;189;186 20:43:58 INFO - PROCESS | 3968 | |13;stanford-crypto-sha256-iterative;74;73;71;71;76;71;75;71;70;69 20:43:58 INFO - PROCESS | 3968 | __end_tp_report 20:43:58 INFO - PROCESS | 3968 | __start_cc_report 20:43:58 INFO - PROCESS | 3968 | _x_x_mozilla_cycle_collect,874 20:43:58 INFO - PROCESS | 3968 | __end_cc_report 20:43:58 INFO - PROCESS | 3968 | __startTimestamp1487825038738__endTimestamp 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | ------- Summary: start ------- 20:43:58 INFO - PROCESS | 3968 | Number of tests: 14 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#0] ai-astar Cycles:10 Average:117.00 Median:118.00 stddev:4.24 (3.6%) stddev-sans-first:1.86 20:43:58 INFO - PROCESS | 3968 | Values: 106.0 116.0 117.0 118.0 117.0 119.0 118.0 122.0 117.0 120.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:133.50 stddev:15.62 (11.7%) stddev-sans-first:16.02 20:43:58 INFO - PROCESS | 3968 | Values: 151.0 129.0 133.0 176.0 131.0 130.0 134.0 131.0 153.0 128.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.00 stddev:2.67 (1.6%) stddev-sans-first:2.80 20:43:58 INFO - PROCESS | 3968 | Values: 165.0 164.0 165.0 168.0 167.0 169.0 165.0 163.0 171.0 163.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#3] audio-fft Cycles:10 Average:84.30 Median:79.50 stddev:13.21 (16.6%) stddev-sans-first:13.66 20:43:58 INFO - PROCESS | 3968 | Values: 76.0 77.0 78.0 119.0 80.0 80.0 78.0 79.0 94.0 82.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:85.00 stddev:9.78 (11.5%) stddev-sans-first:10.01 20:43:58 INFO - PROCESS | 3968 | Values: 84.0 84.0 109.0 85.0 85.0 87.0 100.0 88.0 85.0 106.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.50 stddev:1.60 (1.4%) stddev-sans-first:1.66 20:43:58 INFO - PROCESS | 3968 | Values: 116.0 115.0 114.0 116.0 117.0 118.0 114.0 113.0 114.0 114.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#6] imaging-darkroom Cycles:10 Average:153.20 Median:151.00 stddev:7.69 (5.1%) stddev-sans-first:8.11 20:43:58 INFO - PROCESS | 3968 | Values: 151.0 150.0 151.0 152.0 151.0 151.0 150.0 175.0 150.0 151.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 20:43:58 INFO - PROCESS | 3968 | Values: 97.0 96.0 96.0 96.0 96.0 97.0 96.0 97.0 96.0 97.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:66.00 stddev:1.70 (2.6%) stddev-sans-first:1.74 20:43:58 INFO - PROCESS | 3968 | Values: 65.0 64.0 66.0 66.0 70.0 67.0 68.0 66.0 66.0 65.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 20:43:58 INFO - PROCESS | 3968 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 65.0 67.0 66.0 66.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:91.00 stddev:3.95 (4.3%) stddev-sans-first:3.99 20:43:58 INFO - PROCESS | 3968 | Values: 94.0 97.0 92.0 90.0 89.0 88.0 87.0 87.0 86.0 96.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:140.00 stddev:16.69 (11.9%) stddev-sans-first:17.53 20:43:58 INFO - PROCESS | 3968 | Values: 140.0 138.0 149.0 139.0 138.0 140.0 152.0 140.0 192.0 138.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:188.50 stddev:3.01 (1.6%) stddev-sans-first:3.18 20:43:58 INFO - PROCESS | 3968 | Values: 188.0 188.0 189.0 190.0 186.0 184.0 181.0 191.0 189.0 186.0 20:43:58 INFO - PROCESS | 3968 | 20:43:58 INFO - PROCESS | 3968 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:2.28 (3.2%) stddev-sans-first:2.32 20:43:58 INFO - PROCESS | 3968 | Values: 74.0 73.0 71.0 71.0 76.0 71.0 75.0 71.0 70.0 69.0 20:43:58 INFO - PROCESS | 3968 | -------- Summary: end -------- 20:43:58 INFO - PROCESS | 3968 | 20:43:59 INFO - TEST-INFO | 3968: exit 0 20:43:59 INFO - TEST-OK | kraken | took 64194ms 20:43:59 INFO - SUITE-END | took 1184s 20:43:59 INFO - Completed test suite (00:19:44) 20:43:59 INFO - javascript benchmark 20:43:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6252.417938616233, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2516.8741562921855, 2538.504991526652, 2545.694516658001, 2552.7702506774385, 2555.5527782638032, 4304.677149213813, 4484.439445069366, 4500.707454015491, 4543.318502224668, 4551.294549344852, 4335.5230924598345, 4515.05183911379, 4539.092181563684, 4561.315802629607, 4620.768961551926, 4186.309258972274, 4231.661467082641, 4241.172971270588, 4254.149170165963, 4298.290782020978, 4275.337322714969, 4292.29177185764, 4301.591348821857, 4315.654747620199, 4359.106383191438, 4070.3894415837567, 4108.67130629549, 4125.133721918401, 4230.576942305775, 4305.78471076448, 4149.668026557868, 4168.520620128664, 4185.204811085914, 4194.790260486994, 4223.535411104776, 4118.299889018874, 4142.730722503027, 4161.583841615845, 4180.184863951531, 4193.370994350872, 4421.226285400042, 4434.49003364614, 4504.166729155117, 4523.909521809578, 4548.2495388260895, 4749.382580264578, 4766.76166191689, 4767.880802979918, 4769.689970151929, 4777.737224452654, 4743.644226682978, 4747.691400058985, 4758.809647614091, 4758.857234282977, 4806.75966201692, 4450.933236001462, 4508.954910450919, 4516.20966330894, 4516.638668906479, 4525.366448697185, 4846.39420072491, 4883.0, 4890.486498917562, 4899.6570240083565, 4914.5822605078965, 4765.618750499952, 4776.188048031843, 4784.0, 4791.520847915181, 4836.105320515716, 4835.6131509479155, 4841.104395686809, 4844.128056949712, 4853.6602437829715, 4859.514048595183, 4615.907681846412, 4646.303054541778, 4801.807927682854, 4813.95186048142, 4825.420949486014, 4752.477227504972, 4777.880552986203, 4782.306565547976, 4803.471618122004, 4850.029994001214, 4117.958820411757, 4125.814338354781, 4126.154138401621, 4160.126373461547, 4171.83312667496, 3520.6655367740022, 3607.026102952188, 3622.692071173927, 3632.3824949758605, 3649.671529562352, 4137.54487006429, 4196.307609244502, 4213.346931225665, 4225.112726327444, 4264.189803937242, 4826.662133650681, 4846.927296090562, 4853.3447984522345, 4853.878653033702, 4894.0, 4218.6835987300465, 4224.978875105605, 4228.4080228768, 4230.703850730481, 4263.488381394191, 4222.514410842731, 4259.701820872509, 4370.191514569815, 4401.779911004501, 4405.471343438744, 4563.178627847019, 4574.405327307429, 4588.4952655207235, 4614.584687378152, 4620.283856002325, 4766.785494652819, 4799.784009719501, 4813.15769740301, 4832.806687732451, 4841.878953026203, 4557.361969324231, 4562.22442184836, 4564.7032942858605, 4597.931031034604, 4605.7927393268055, 4593.311003349524, 4615.723056616479, 4704.647151463585, 4729.55069268419, 4754.429468463806, 4815.494373090915, 4816.084943860656, 4823.758812059453, 4862.95137048625, 4866.853994380174, 4692.507286734844, 4692.671512994058, 4698.812047518061, 4708.952910470852, 4749.928751068667, 4668.183067963025, 4686.062787442457, 4694.483606803181, 4705.153072446992, 4744.217204161413, 4674.322223277673, 4682.086993036325, 4686.531346865286, 4686.789094490688, 4733.124371991055, 4741.525847415231, 4749.453812811433, 4764.047190561832, 4768.523147685204, 4805.783739731651, 4505.121501307213, 4541.477730061085, 4695.22528782747, 4701.670883038291, 4737.0525894820485], "value": 4443.263435518532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.4356182446606, 535.3629181274281, 540.0791650236346, 540.5864513647058, 563.8844036972419, 37563.933983630886, 38548.80725596375, 38618.420723689094, 38669.419958700644, 38743.41884871722, 39258.60741392592, 39599.45607614936, 39878.401823972665, 40098.19803603926, 40260.59739402605, 7529.736459223928, 7783.41624378171, 7790.065192176947, 9131.08689131085, 9376.296777741696, 9284.814303713922, 9386.671466498674, 9392.953035234848, 9416.482093484856, 9418.246540276796, 9411.247100231965, 9554.0, 9556.665516706882, 9574.28192885533, 9580.137787599115, 9341.392809467397, 9353.064693530661, 9416.1525462708, 9459.0, 9478.952605236998, 9360.859587106199, 9393.154616084517, 9415.387999779992, 9453.52732363379, 9453.90546094544, 33032.33935321304, 33588.0, 33821.15447113817, 33832.83083584591, 33906.32187356239, 3262.7383945697247, 3422.144463884029, 3437.295354452331, 3483.3207524532727, 3487.6337984511515, 25511.489770204676, 25531.36171595725, 25640.358991025187, 26011.349716257246, 26243.0, 3363.814990175539, 3524.2951409718157, 3575.4636804479537, 3646.4165733482782, 3687.1703866630064, 237864.0, 237902.0, 239225.0, 240564.99999999825, 240714.99999999825, 38783.418248726295, 39828.2034359317, 45681.85795355143, 47043.70822522514, 47558.0, 44155.779221104334, 47108.293375599394, 47593.762031190316, 47868.0, 47969.28046079312, 12014.519419223134, 12091.879081209252, 12109.69725756863, 12121.0, 12133.757324853454, 8445.788855278544, 8471.152884711479, 8484.406091573654, 8507.574621268912, 8515.0, 15395.537423944706, 15546.689066218612, 15620.0, 15659.217039147998, 15886.52340429789, 6264.0, 6339.2709838368255, 6392.680365981775, 6436.806895793134, 6570.737170513126, 728.2644529025722, 734.0897287363631, 745.0388998192271, 761.4137114421975, 763.6181909045478, 25569.23292301234, 26622.754491017964, 26808.0, 27201.0, 27285.181444556696, 866.5970323799463, 889.768660148366, 896.1441823058963, 916.3585490156917, 920.1672486399818, 13827.377768000464, 14378.0, 14379.281035948161, 14595.197264150362, 14626.195559244345, 314988.0, 318161.0, 319186.0, 319544.0, 320110.0, 48572.7571362148, 48873.0, 49004.0, 49064.26403603949, 49170.50829491731, 1153.2676750263643, 1205.3189947679532, 1207.3299318878107, 1208.9516419343304, 1213.342779313301, 24447.75552244455, 25074.24777256685, 26292.21123366302, 26418.73581264163, 26472.338191544593, 40711.592884071964, 41158.1768364637, 41173.0, 41244.0, 41270.58729412667, 258379.0, 259090.0, 260421.0, 260552.0, 260642.0, 23158.0, 23847.40381490407, 23889.0, 25056.62415063812, 25189.622155668047, 37424.0, 37593.2481350366, 44000.0, 44073.55926440695, 45740.771296143306, 35478.29043419169, 37132.814335928146, 38416.0236676932, 38770.41844372393, 39450.60549394469, 34385.72992208206, 37326.813365933, 37586.0, 37650.81174594109, 38622.0, 36071.0, 36077.27845443129, 36229.818850905576, 36237.63762362343, 37104.81447592745, 31030.0, 33481.83259083689, 33700.66299337073, 34392.0, 34449.48325775166, 25740.485190295716, 26120.477590447703, 26187.869060654575, 26308.342291442867, 26628.46743065089], "value": 16941.400618534015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.0891418537714, 394.5955395719389, 401.53020965470404, 406.912513809531, 419.5133644971833, 12737.108402411835, 15999.280032398541, 16498.752518712216, 17841.91079044603, 18032.819671803296, 16973.830261697378, 18335.908320458413, 18372.081395930218, 18527.444176674686, 18694.252229910788, 5074.74626268686, 5280.762365693534, 5294.602904782138, 5391.16436952273, 5617.045102332613, 5636.351819540744, 5658.915116273239, 5680.715964201794, 5697.8005769798065, 5717.656940583557, 5550.750216240279, 5677.37548869624, 5700.458456446631, 5714.285714285715, 5777.104548794943, 5659.773609055633, 5722.456366645183, 5726.3700992890745, 5738.081906894919, 5750.309962804469, 5451.454854514556, 5515.889682206333, 5529.253550770634, 5619.662820230799, 5637.689927054011, 13339.333033348294, 13585.864141358657, 13615.455381784717, 21174.517783755142, 21404.0, 2101.3275751759443, 2117.1002324012234, 2124.2246579998346, 2152.2251989283845, 2433.914812981537, 9242.537873106317, 9352.316583015054, 9685.225181985425, 9888.0, 12927.806082908764, 1675.0201132337597, 1728.5419363868584, 1762.0396883698415, 1764.4882983934644, 1797.7393277974752, 80302.59848700718, 81388.59305703493, 82357.17642823554, 82438.0, 84126.57936710278, 10683.83974240387, 10869.347839129678, 10987.725306867233, 11153.888461115448, 11253.606123785628, 12201.69495762613, 14928.70142597142, 15501.302441390164, 15503.14732689713, 15508.689826203412, 6568.113304703899, 6753.932460675429, 6903.102596662402, 6910.101686780687, 6924.134483189601, 6292.716827742763, 6304.621722696653, 6338.366163383625, 6479.352064793483, 6480.837979050561, 4053.9797301013305, 4076.2458945095254, 4149.0, 4190.224808410454, 4191.497020357577, 1929.63336965976, 2009.156154415149, 2011.1955217912953, 2012.5471768852185, 2034.08466190215, 761.6686741267566, 773.5165521549031, 791.2751244744271, 798.4371018431991, 803.863343231652, 16858.0, 18139.365122220657, 18534.351297704514, 18597.0, 18973.525661858563, 771.8880762289436, 772.729544659376, 777.3431450424382, 780.5355813291083, 799.6161842315605, 9855.605775769032, 10050.497475126214, 10286.742831429125, 10298.794024119476, 10412.5834966602, 470311.0, 474477.0, 475112.0, 479884.0, 481073.0, 79837.60081199557, 80492.59753701194, 82953.58523207466, 83448.0, 83646.58176709077, 911.0798093925183, 918.669279059538, 949.0699114867468, 952.0146648219198, 958.1472489484364, 15251.542453726406, 15692.76460853111, 16207.837921620632, 16284.547270504585, 16442.424515141913, 39132.217355653294, 39262.21475570415, 39491.605083949944, 39692.60307397004, 40595.0, 48724.26913596228, 52024.21963670472, 52156.0, 52382.21426678679, 53447.19829202643, 5174.818881339135, 5210.869728256672, 6793.830154246183, 7532.8493430132185, 8157.306628936607, 8328.208820162075, 11474.196806223485, 12386.75226495483, 12586.559470418491, 12967.22196668203, 10810.837837432287, 10843.783124337311, 10923.399213043358, 11183.273087249303, 11356.034737047114, 10854.0, 10896.400697961688, 11087.168462365193, 11440.0, 11529.884701152881, 10547.156227501628, 10618.469076546296, 10945.452727363441, 11334.206605537533, 11732.94133529327, 6975.546589471668, 7210.242924493063, 7275.927240727737, 8188.918110818816, 8502.74491765248, 10402.895971040496, 10496.317739346918, 10551.472426378497, 10771.084457821173, 10844.0], "value": 8447.453604368464, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.5237590655317, 1348.811166436699, 1355.0920883008384, 1357.4434481862438, 1358.300475255243, 7078.68145933433, 7272.16370117436, 7278.5632862028315, 7607.847843043136, 7613.505122167056, 7547.886781698266, 7658.54048757075, 7669.309762121408, 7680.462367634268, 7716.498427602202, 2002.6895831146196, 2005.378332716855, 2015.606956643455, 2025.2911480981613, 2032.756069271682, 1988.5823976964855, 2027.1182035814395, 2038.9082491287927, 2040.3470889315424, 2047.2834507922257, 2067.669172932331, 2083.291680828518, 2103.5372218111993, 2105.7262555867715, 2108.6099071671715, 2033.7559492860878, 2044.1516770540209, 2048.1193086972594, 2049.1291201239414, 2052.79472052795, 1713.2632967823831, 1760.8855424397373, 1995.4013795861183, 1999.3502111813573, 2046.6623007203793, 7277.345038946467, 7487.8502429951095, 7490.176080631126, 7492.7752167435065, 7493.737719179802, 4427.800748966304, 4564.292534657134, 4575.4738205106505, 4585.426821647294, 4586.678932474728, 2759.889604415841, 2779.805413621047, 2780.3883145708114, 2791.1766029021487, 2802.369466869938, 1153.1862256095185, 1186.4898093819654, 1670.3652612007481, 1682.5457126575877, 1759.7624320716668, 9076.636934522678, 9077.863832042525, 9094.317926155498, 9100.99889012208, 9201.539923003824, 3773.7924414156937, 3785.3186426443503, 4026.476558047435, 4053.0, 4072.165206132736, 8038.477498962666, 8090.1909809018625, 8096.987876515435, 8104.473209241499, 8116.147804480444, 4548.385967894292, 4567.748773817472, 4573.085382923428, 4574.611158051536, 4579.9084018319445, 4015.6185162409524, 4098.938515922264, 4107.404426358161, 4128.318827393447, 4137.358709400048, 3171.936561268808, 3194.105650417887, 3276.2137087098927, 3281.5733954585753, 3284.6040432815958, 959.5154447004358, 974.3228456222857, 976.1556253840376, 979.5053497983615, 982.7837875667342, 767.6699019421591, 786.8898354230412, 797.5613412623034, 804.5213098206558, 806.8381530595923, 4618.214903466419, 4747.976260118678, 4774.164521208844, 4802.639802014863, 4954.653174277766, 519.3248776590417, 521.9347581552306, 539.2827539372626, 544.7113030094057, 548.101114172757, 3373.5951685797863, 3489.5986961499243, 3508.386032444312, 3510.9122271942897, 3575.9782983379005, 8016.959915200387, 8345.95827020861, 8501.957490212633, 8822.470651760916, 9035.864462033074, 9028.052054534312, 9077.183053525214, 9087.409318394415, 9094.272458203328, 9122.452652840851, 6606.867862642816, 7004.3696067354185, 7055.858882822417, 7065.399441047569, 7074.504784665127, 4759.3336932830125, 4777.856664300077, 4849.175640141255, 4905.117078925828, 5066.316047333563, 7985.640646171052, 8171.5505647189975, 8201.425900187169, 8229.806722730884, 8270.37972152079, 8419.663213471393, 8463.322934165375, 8472.322214222972, 8538.530380829114, 8545.572721363782, 6462.7091780870915, 6667.666616669244, 6670.099536562696, 6676.165479315086, 6682.799516014527, 7069.399101076466, 7127.643617818984, 7128.572285662877, 7159.928400716134, 7204.891926621, 6433.099366088658, 6497.772577959938, 6573.605583664996, 6694.430973367123, 6717.832054198684, 6420.0, 6518.0, 6534.771283005262, 6616.933830661632, 6657.3009833966735, 5647.491725744702, 5712.171735098304, 5753.884922301447, 5775.422457754191, 5779.653220806765, 3928.45001699768, 3952.4268980997017, 4029.7582145071387, 4068.3897415386746, 4146.0464093258115, 4461.821527138878, 4466.910661786811, 4552.11233809404, 4574.702644328108, 4697.9530204698885], "value": 3888.3988834168395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7326831607978, 361.67087949965526, 361.94932709420704, 363.1321142469497, 367.827121253011, 5031.672941258816, 5040.5211504907065, 5086.92369614456, 5093.617978651602, 5093.898122037557, 5011.248312753089, 5049.747512624373, 5053.191489361703, 5054.444011158769, 5057.620678449112, 3895.0330083307963, 4005.7396269242404, 4018.7588744675268, 4036.15240799431, 4045.370509169049, 4001.379786133154, 4012.779297138656, 4018.959810401888, 4022.8994275143064, 4037.8586749463775, 3973.364261718126, 4001.279769641463, 4022.1754540319166, 4038.838446462138, 4070.14526949341, 3667.2665466906724, 3840.6735427488697, 4055.5336136344404, 4060.1879624075154, 4069.7354671946227, 3370.713489353408, 3382.289719158955, 3385.1706331948585, 3386.0879643506564, 3404.5063465797566, 4857.757112144379, 4895.4125504939275, 4917.262410638397, 4949.455559888409, 4960.404751429815, 4463.66522510813, 4480.0, 4490.730556166641, 4494.550469993861, 4495.14592227479, 4973.254011898208, 5241.449647787003, 5242.947570524284, 5250.65870718402, 5252.763625636855, 3722.8696995605296, 3727.38498147808, 3737.4580685800406, 3744.3821769408014, 3767.0, 5247.475252474722, 5504.366997795265, 5529.944700553024, 5560.221568980346, 5572.637778544382, 3505.8422370993367, 3756.398976163801, 3772.830222639988, 3793.791341476245, 3801.163743976321, 3824.5028146341356, 3832.865849695247, 3844.6732027777393, 3850.5356571794073, 3866.5940076292145, 4388.27593447084, 4396.076609055007, 4420.204363214588, 4438.267685831802, 4440.578145076212, 5031.20767973778, 5241.449647787003, 5255.449642355515, 5284.39229488606, 5290.418054014055, 1276.6467544402776, 1282.557517656407, 1290.1936289818864, 1292.954746583884, 1293.8641442648386, 850.510956200175, 877.5783231165552, 887.9467231966103, 889.755317287751, 896.2381975320925, 4468.4637843458995, 4474.351219073216, 4479.417675702203, 4491.348754430589, 4495.483019452739, 5269.420363759983, 5277.577793776489, 5279.313689220377, 5307.548858347007, 5336.7865285388925, 4271.402003719481, 4457.258785846681, 4458.286674132123, 4468.687191896601, 4479.939227955112, 2795.434556648243, 2806.7614252788335, 2815.90144344951, 2826.4064546445065, 2831.377097038648, 4919.532644398776, 4932.654714169974, 4933.777979990909, 4939.8885250818275, 4945.851624451272, 5031.899362012739, 5318.867028324323, 5318.97340513295, 5357.5446087082255, 5359.785608575691, 4862.6839255448285, 4880.170371036932, 4883.316335713003, 4912.533309335536, 4914.70511769295, 5252.266751971763, 5268.760776669977, 5279.841604751864, 5285.762140703754, 5302.70835104073, 4860.6111511078325, 5108.157154069537, 5144.768485418091, 5180.539243859137, 5220.399654039834, 4812.711237325631, 4862.00328932575, 4865.739954230745, 4922.735541650881, 4949.727764972961, 3697.759645623026, 3769.453429252798, 3807.942880856734, 3814.0, 3830.157365379612, 5383.434739352311, 5396.892062158657, 5402.027635025733, 5404.32445944257, 5411.729413529387, 4993.750312484434, 5260.86847828792, 5265.631405801557, 5282.3132992712235, 5283.0226408113085, 5284.630075894805, 5291.656042357235, 5291.656042357235, 5309.522143006993, 5317.893642127058, 5240.371155461369, 5241.816536421207, 5246.449122842047, 5253.763580638804, 5272.920906186334, 5208.817691380784, 5244.939980940054, 5281.837995640953, 5338.54622357104, 5379.139337706105, 5203.245529398292, 5206.479352064764, 5224.791008359775, 5241.383445422646, 5241.973790131177], "value": 3944.2310232603645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.3106141958816, 652.4226059936957, 698.954567953083, 735.6138027535543, 750.197288900876, 10926.28979116358, 11595.0, 11629.941850290747, 11639.534418623267, 11646.883531164685, 12167.391630418466, 12355.258684478938, 12388.566400175972, 12393.44229509672, 12419.937900310508, 777.7666699990009, 792.528445574882, 796.7052190689453, 808.8382323535287, 815.3966065111804, 816.7386436340373, 817.4727300890921, 817.5094943034192, 817.9059408168048, 818.7011740714626, 841.0874201491389, 845.0, 845.2646197807916, 845.7166849105527, 847.690592933581, 835.1106072033296, 835.5195762436593, 836.5399030533182, 838.304207507767, 844.7803571071504, 617.5491890919609, 617.8949578571609, 618.5731845026962, 618.8298217990043, 620.4230066038582, 12761.361931903368, 12926.741465170644, 12999.935000324938, 13081.476740930446, 13166.736665266737, 10637.095846853026, 12139.757204855941, 12173.756524869541, 12913.612591622266, 12916.354182290848, 9801.656942006995, 9938.453385063818, 10000.799984000352, 10014.9499252504, 10107.545160467796, 3073.754099672021, 3121.172889184368, 3152.7004934531183, 3269.0683155300626, 3271.509273608954, 45385.0, 45770.31344529835, 52226.73886630581, 60422.0, 61149.38850611482, 9082.636694532144, 9185.770355741026, 9289.396189247676, 9322.813543729088, 9330.253579713606, 18200.63598728018, 18203.54491137732, 18254.543636409195, 18271.817281827276, 18319.0, 6318.052292156213, 6347.143135676624, 6558.114654521674, 6692.866142677217, 6709.832254193587, 3310.5696259486112, 3365.899023029264, 3472.2881809229048, 3478.1826270826336, 3479.7564170508326, 2985.820850748962, 3098.969010309868, 3106.48742957414, 3123.2816452216116, 3148.7166155046157, 491.7418355363463, 499.83505443203654, 502.11376919736693, 504.11276153969277, 511.289307862072, 618.3476432363899, 637.3021541412146, 641.7176442365344, 651.8761435327273, 661.6063442251949, 8021.478603890729, 8518.233358997595, 8571.571421428904, 8720.738377848675, 8821.691240806542, 592.2596754057428, 618.6813790897692, 620.3269452643932, 620.8292719502083, 628.6071205496564, 3808.409696497047, 3827.13889374894, 3918.1771827916455, 4001.6598589119667, 4001.8599349022643, 62224.0, 64018.35981640217, 64212.35787642064, 65750.34249657538, 67455.0, 56833.0, 57283.713581431824, 57599.42400575941, 58245.70877145672, 59144.0, 52036.73981630067, 52094.47905520972, 52194.21708674373, 52496.47503524916, 52947.47052529502, 7568.205338439385, 8005.559694216874, 8039.799005024921, 8132.0, 8222.712205072794, 35678.0, 36054.27891442104, 36263.456048159824, 36383.81808090943, 37250.44124338083, 32989.83505082459, 43992.56007439885, 44052.33921491244, 44486.11027779362, 45845.31232031589, 11117.555297788322, 11140.220184587, 11224.887751122384, 11364.14768892353, 11369.431528423711, 11898.821517677416, 12262.20295680778, 12672.619821405373, 12806.167599106027, 12991.805122922975, 11211.0, 11607.245529040585, 11617.186796924134, 11838.940805295919, 12155.392230388268, 10186.185105191715, 10438.321509101885, 10503.684889453329, 10596.735081623021, 10726.678199654023, 10266.0, 10548.736281593021, 10619.575216991234, 10687.839682404614, 10923.508442120283, 8771.91228087711, 8876.822463550821, 9053.90946090557, 9108.089191080839, 9126.406783559047, 9325.626974920928, 9330.720078397659, 9369.109934556207, 9387.10822471864, 9526.285528585246], "value": 6125.2940997543965, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 117.0, 118.0, 117.0, 119.0, 118.0, 122.0, 117.0, 120.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 129.0, 133.0, 176.0, 131.0, 130.0, 134.0, 131.0, 153.0, 128.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 168.0, 167.0, 169.0, 165.0, 163.0, 171.0, 163.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 119.0, 80.0, 80.0, 78.0, 79.0, 94.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 109.0, 85.0, 85.0, 87.0, 100.0, 88.0, 85.0, 106.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 116.0, 117.0, 118.0, 114.0, 113.0, 114.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 152.0, 151.0, 151.0, 150.0, 175.0, 150.0, 151.0], "value": 153.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 66.0, 70.0, 67.0, 68.0, 66.0, 66.0, 65.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 92.0, 90.0, 89.0, 88.0, 87.0, 87.0, 86.0, 96.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 149.0, 139.0, 138.0, 140.0, 152.0, 140.0, 192.0, 138.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 189.0, 190.0, 186.0, 184.0, 181.0, 191.0, 189.0, 186.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 71.0, 71.0, 76.0, 71.0, 75.0, 71.0, 70.0, 69.0], "value": 72.1, "unit": "score"}]}]} 20:44:00 INFO - Return code: 0 20:44:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:44:00 ERROR - # TBPL SUCCESS # 20:44:00 INFO - Running post-action listener: _resource_record_post_action 20:44:00 INFO - [mozharness: 2017-02-23 04:44:00.071493Z] Finished run-tests step (success) 20:44:00 INFO - Running post-run listener: _resource_record_post_run 20:44:00 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 7471104; Write bytes: 370638848; Read time: 1560; Write time: 257992 20:44:00 INFO - TinderboxPrint: CPU usage
13.5% 20:44:00 INFO - TinderboxPrint: I/O read bytes / time
7,471,104 / 1,560 20:44:00 INFO - TinderboxPrint: I/O write bytes / time
370,638,848 / 257,992 20:44:00 INFO - TinderboxPrint: CPU idle
8,282.5 (86.5%) 20:44:00 INFO - TinderboxPrint: CPU user
1,236.9 (12.9%) 20:44:00 INFO - TinderboxPrint: Swap in / out
0 / 0 20:44:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113963008; Read time: 96; Write time: 99992 20:44:00 INFO - run-tests - Wall time: 1185s; CPU: 14.0%; Read bytes: 5435392; Write bytes: 256675840; Read time: 1376; Write time: 158000 20:44:00 INFO - Running post-run listener: _upload_blobber_files 20:44:00 INFO - Blob upload gear active. 20:44:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:44:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:44:01 INFO - (blobuploader) - INFO - Open directory for files ... 20:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:44:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:01 INFO - (blobuploader) - INFO - Done attempting. 20:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:44:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:04 INFO - (blobuploader) - INFO - Done attempting. 20:44:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:44:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:44:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:05 INFO - (blobuploader) - INFO - Done attempting. 20:44:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:44:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:44:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:44:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:44:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:44:06 INFO - (blobuploader) - INFO - Done attempting. 20:44:06 INFO - (blobuploader) - INFO - Iteration through files over. 20:44:06 INFO - Return code: 0 20:44:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:44:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:44:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef7b51db24251d64ffa6a76f31719c126ba1b0cd5366270265e427b6adb69efddf2d7977c0cdc6a624393e223b1cd6d737d1364083c1200d1ca7ad5f7e48e1fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e6da96947f3dbc4ed53fc113cda970a60cd4a10beb66408786e42134d0ccce7d71579da63ac1e015c3866379709aa58cb752c654b8ca6a1d7e8054da30aacd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331e4b665364c8beaca6e7fb5e31d8b35e9e33c122cd7e105d74f9ac853e21fb63abcc0c365374177404c51bc6e957d68dc960b1dbb1d0a346368966d3ab4996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9ce611858b3afbc0ce3b43f2285a9d2f256483383eacc1bcaeea683756eb4234eff0196f3c5082270a1502fc006078d5b8a97634b5580bc0e665fc3a4f1fa74"} 20:44:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:44:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:44:06 INFO - Contents: 20:44:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef7b51db24251d64ffa6a76f31719c126ba1b0cd5366270265e427b6adb69efddf2d7977c0cdc6a624393e223b1cd6d737d1364083c1200d1ca7ad5f7e48e1fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e6da96947f3dbc4ed53fc113cda970a60cd4a10beb66408786e42134d0ccce7d71579da63ac1e015c3866379709aa58cb752c654b8ca6a1d7e8054da30aacd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331e4b665364c8beaca6e7fb5e31d8b35e9e33c122cd7e105d74f9ac853e21fb63abcc0c365374177404c51bc6e957d68dc960b1dbb1d0a346368966d3ab4996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9ce611858b3afbc0ce3b43f2285a9d2f256483383eacc1bcaeea683756eb4234eff0196f3c5082270a1502fc006078d5b8a97634b5580bc0e665fc3a4f1fa74"} 20:44:06 INFO - Running post-run listener: copy_logs_to_upload_dir 20:44:06 INFO - Copying logs to upload dir... 20:44:06 INFO - mkdir: /builds/slave/test/build/upload/logs 20:44:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1356.417154 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 36 secs) (at 2017-02-22 20:44:06.815957) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:44:06.819569) ========= 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=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 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/ef7b51db24251d64ffa6a76f31719c126ba1b0cd5366270265e427b6adb69efddf2d7977c0cdc6a624393e223b1cd6d737d1364083c1200d1ca7ad5f7e48e1fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e6da96947f3dbc4ed53fc113cda970a60cd4a10beb66408786e42134d0ccce7d71579da63ac1e015c3866379709aa58cb752c654b8ca6a1d7e8054da30aacd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331e4b665364c8beaca6e7fb5e31d8b35e9e33c122cd7e105d74f9ac853e21fb63abcc0c365374177404c51bc6e957d68dc960b1dbb1d0a346368966d3ab4996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9ce611858b3afbc0ce3b43f2285a9d2f256483383eacc1bcaeea683756eb4234eff0196f3c5082270a1502fc006078d5b8a97634b5580bc0e665fc3a4f1fa74"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef7b51db24251d64ffa6a76f31719c126ba1b0cd5366270265e427b6adb69efddf2d7977c0cdc6a624393e223b1cd6d737d1364083c1200d1ca7ad5f7e48e1fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e6da96947f3dbc4ed53fc113cda970a60cd4a10beb66408786e42134d0ccce7d71579da63ac1e015c3866379709aa58cb752c654b8ca6a1d7e8054da30aacd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/331e4b665364c8beaca6e7fb5e31d8b35e9e33c122cd7e105d74f9ac853e21fb63abcc0c365374177404c51bc6e957d68dc960b1dbb1d0a346368966d3ab4996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9ce611858b3afbc0ce3b43f2285a9d2f256483383eacc1bcaeea683756eb4234eff0196f3c5082270a1502fc006078d5b8a97634b5580bc0e665fc3a4f1fa74"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:44:06.844036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:44:06.852567) ========= 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=3a3620fb28aea1604ab609ef000001d2-1487821644.788417-401907856 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:44:06.926925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:44:06.927770) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:44:06.928080) ========= ========= Total master_lag: 0.18 =========